Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

EN+: "Preparing" timeout before RemoteStartTransaction could be sent on long intervals #15752

Closed
jeffborg opened this issue Aug 27, 2024 · 10 comments
Closed

EN+: "Preparing" timeout before RemoteStartTransaction could be sent on long intervals #15752

jeffborg opened this issue Aug 27, 2024 · 10 comments
Assignees
@premultiply
Labels
question Rather clarification than issue

Comments

@jeffborg
Copy link
Contributor

Describe the bug

evcc is now unworkable when your ocpp evse decides to jump to finishing status upon plugging in

Prior to 0.130.0 evcc was quite happy to break ocpp spec and jump from Finishing back to SuspendedEVSE state (IE tell the evse to start charging!)

This happens even if the mode is set to min + solar or it's off (no solar) - I spent almost 1/2 hour trying to evcc into a working state this morning!

Only way to get going now seems to be to restart both the evse & evcc & unplug replug car! & make sure evcc is in min+solar or fast mode before plugging the car in! And not do this too quickly either otherwise evcc crashed see

[ocpp  ] TRACE 2024/08/28 08:23:02 send SN10052307203612: [2,"1810363461","ChangeConfiguration",{"key":"WebSocketPingInterval","value":"30"}]
[ocpp  ] TRACE 2024/08/28 08:23:02 recv SN10052307203612: [3,"1810363461",{"status":"NotSupported"}]
panic: send on closed channel

goroutine 52 [running]:
github.com/evcc-io/evcc/charger.(*OCPP).configure.func1(0x0?, {0x0, 0x0})
	github.com/evcc-io/evcc/charger/ocpp.go:375 +0xc0
github.com/lorenzodonini/ocpp-go/ocpp1%2e6.(*centralSystem).ChangeConfiguration.func1({0x344b480?, 0x40014a63b0?}, {0x0?, 0x0?})
	github.com/lorenzodonini/ocpp-go@v0.18.0/ocpp1.6/central_system.go:78 +0x54
created by github.com/lorenzodonini/ocpp-go/ocpp1%2e6.(*centralSystem).handleIncomingConfirmation in goroutine 87
	github.com/lorenzodonini/ocpp-go@v0.18.0/ocpp1.6/central_system.go:545 +0xc8

Note this evse can't be set to free vend mode as this disables ocpp!

I have tried other combos to try and get going

  • unplug replug car with evcc enabled for charging - no go ends in finishing state
  • unplug car, turn off evse, turn on evse, plug car in no go ends in finishing state
  • restart evcc in enabled mode - doesn't even boot up timeout for ocpp configure

relying upon vendors to fix issues is really a non starter, my only real option is evcc isn't fixed is to implement ocpp in node red and just connect this to evcc.

Steps to reproduce

  1. unplug car
  2. plug car back in
  3. evcc still wants to unplug and plug car back in
  4. jump to 1 evcc still wants to unplug and replug car in!

Configuration details

chargers:
  - name: solax
    type: template
    template: ocpp
    timeout: 2m
    # added for 0.130.0
    remotestart: true
    # commented out 19/8 for 0.130.0 update from 0.129.0
    # getconfiguration: false
    # metervalues: Energy.Active.Import.Register,Power.Active.Import,Current.Import
    # bootnotification: false
    # meterinterval: 10s

Log details

[ocpp  ] DEBUG 2024/08/28 08:07:55 charge point connected: SN10052307203612
[ocpp  ] TRACE 2024/08/28 08:07:57 send SN10052307203612: [2,"56388924","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:07:57 recv SN10052307203612: [3,"56388924",{"status":"Accepted"}]
[site  ] DEBUG 2024/08/28 08:07:58 ----
[lp-1  ] DEBUG 2024/08/28 08:07:58 charge power: 0W
[lp-1  ] DEBUG 2024/08/28 08:07:58 charge currents: [0 0 0]A
[circuit-main] DEBUG 2024/08/28 08:07:58 power: 558.75W
[circuit-main] DEBUG 2024/08/28 08:07:58 current: 0A
[site  ] DEBUG 2024/08/28 08:07:58 pv power: 807W
[site  ] DEBUG 2024/08/28 08:07:58 grid meter: 559W
[site  ] DEBUG 2024/08/28 08:07:58 site power: 559W
[lp-1  ] DEBUG 2024/08/28 08:07:58 vehicle odometer: 81195km
[lp-1  ] DEBUG 2024/08/28 08:07:58 charge voltages: [244 0 0]V
[lp-1  ] DEBUG 2024/08/28 08:07:58 detected connected phases: 1p
[lp-1  ] DEBUG 2024/08/28 08:07:58 charge total import: 0.000kWh
[lp-1  ] DEBUG 2024/08/28 08:07:58 charger status: A
[ocpp  ] TRACE 2024/08/28 08:07:58 send SN10052307203612: [2,"4288998214","SetChargingProfile",{"connectorId":1,"csChargingProfiles":{"chargingProfileId":3,"stackLevel":5,"chargingProfilePurpose":"TxDefaultProfile","chargingProfileKind":"Absolute","chargingSchedule":{"startSchedule":"2024-08-27T22:07:58Z","chargingRateUnit":"A","chargingSchedulePeriod":[{"startPeriod":0,"limit":0}]}}}]
[ocpp  ] TRACE 2024/08/28 08:07:59 recv SN10052307203612: [3,"4288998214",{"status":"Accepted"}]
[lp-1  ] DEBUG 2024/08/28 08:07:59 charger disable
[ocpp  ] TRACE 2024/08/28 08:07:59 send SN10052307203612: [2,"3105039279","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[lp-1  ] DEBUG 2024/08/28 08:07:59 wake-up timer: stop
[ocpp  ] TRACE 2024/08/28 08:08:00 recv SN10052307203612: [3,"3105039279",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:01 send SN10052307203612: [2,"1765718411","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:08:01 recv SN10052307203612: [3,"1765718411",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:03 send SN10052307203612: [2,"3251360294","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:08:04 recv SN10052307203612: [2,"4","BootNotification",{"chargePointModel":"23","chargePointSerialNumber":"SN10052307203612","chargePointVendor":"EN+","firmwareVersion":"1.3.53"}]
[ocpp  ] TRACE 2024/08/28 08:08:04 recv SN10052307203612: [3,"3251360294",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:04 send SN10052307203612: [3,"4",{"currentTime":"2024-08-27T22:08:04Z","interval":60,"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:05 send SN10052307203612: [2,"2984233680","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:08:05 recv SN10052307203612: [2,"6","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/28 08:08:05 recv SN10052307203612: [2,"offline-5","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"1970-01-01T00:00:32Z","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":"0.0","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"}]}]}]
[ocpp  ] TRACE 2024/08/28 08:08:05 send SN10052307203612: [3,"6",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:05 send SN10052307203612: [3,"offline-5",{}]
[ocpp  ] TRACE 2024/08/28 08:08:05 recv SN10052307203612: [2,"7","StatusNotification",{"connectorId":0,"status":"Available","errorCode":"NoError","timestamp":"2024-08-27T22:08:01Z","vendorId":"EN+","info":"{\"reason\":\"None\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/28 08:08:05 send SN10052307203612: [3,"7",{}]
[ocpp  ] TRACE 2024/08/28 08:08:06 recv SN10052307203612: [3,"2984233680",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:06 recv SN10052307203612: [2,"8","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-27T22:08:04Z","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.3","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"}]}]}]
[ocpp  ] TRACE 2024/08/28 08:08:06 send SN10052307203612: [3,"8",{}]
[ocpp  ] TRACE 2024/08/28 08:08:06 recv SN10052307203612: [2,"9","StatusNotification",{"connectorId":1,"status":"Available","errorCode":"NoError","timestamp":"2024-08-27T22:08:01Z","vendorId":"EN+","info":"{\"reason\":\"None\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/28 08:08:06 recv SN10052307203612: [2,"10","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/28 08:08:06 send SN10052307203612: [3,"10",{"currentTime":"2024-08-27T22:08:06Z"}]
[ocpp  ] TRACE 2024/08/28 08:08:06 send SN10052307203612: [3,"9",{}]
[ocpp  ] TRACE 2024/08/28 08:08:15 send SN10052307203612: [2,"1441389827","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:08:15 recv SN10052307203612: [3,"1441389827",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:15 recv SN10052307203612: [2,"11","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-27T22:08: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":"245.3","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"}]}]}]
[ocpp  ] TRACE 2024/08/28 08:08:15 send SN10052307203612: [3,"11",{}]
[ocpp  ] TRACE 2024/08/28 08:08:25 send SN10052307203612: [2,"4084174118","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:08:25 recv SN10052307203612: [3,"4084174118",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:26 recv SN10052307203612: [2,"12","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-27T22:08:24Z","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.3","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"}]}]}]
[ocpp  ] TRACE 2024/08/28 08:08:26 send SN10052307203612: [3,"12",{}]
[site  ] DEBUG 2024/08/28 08:08:28 ----
[lp-1  ] DEBUG 2024/08/28 08:08:28 charge power: 0W
[lp-1  ] DEBUG 2024/08/28 08:08:28 charge currents: [0 0 0]A
[circuit-main] DEBUG 2024/08/28 08:08:28 power: 559.51W
[circuit-main] DEBUG 2024/08/28 08:08:28 current: 0A
[site  ] DEBUG 2024/08/28 08:08:28 pv power: 835W
[site  ] DEBUG 2024/08/28 08:08:28 grid meter: 560W
[site  ] DEBUG 2024/08/28 08:08:28 site power: 560W
[lp-1  ] DEBUG 2024/08/28 08:08:28 charge voltages: [245 0 0]V
[lp-1  ] DEBUG 2024/08/28 08:08:28 detected connected phases: 1p
[lp-1  ] DEBUG 2024/08/28 08:08:28 charge total import: 0.000kWh
[lp-1  ] DEBUG 2024/08/28 08:08:28 charger status: A
[ocpp  ] TRACE 2024/08/28 08:08:35 send SN10052307203612: [2,"2212275582","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:08:35 recv SN10052307203612: [2,"13","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":0,\"totalPowerFactor\":256,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":0,\"ccVolt\":256,\"offset0\":2012,\"offset1\":2017,\"leakcurr\":0,\"AMBTemp\":247,\"lockStatus\":1,\"ctCurrent\":9}"}]
[ocpp  ] TRACE 2024/08/28 08:08:35 send SN10052307203612: [3,"13",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:36 recv SN10052307203612: [3,"2212275582",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:36 recv SN10052307203612: [2,"14","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-27T22:08:34Z","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.3","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"}]}]}]
[ocpp  ] TRACE 2024/08/28 08:08:36 send SN10052307203612: [3,"14",{}]
[ocpp  ] TRACE 2024/08/28 08:08:36 recv SN10052307203612: [2,"15","StatusNotification",{"connectorId":1,"status":"Preparing","errorCode":"NoError","timestamp":"2024-08-27T22:08:33Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/28 08:08:36 send SN10052307203612: [3,"15",{}]
[ocpp  ] TRACE 2024/08/28 08:08:45 send SN10052307203612: [2,"3545938426","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:08:45 recv SN10052307203612: [3,"3545938426",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:46 recv SN10052307203612: [2,"16","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-27T22:08:44Z","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.3","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"}]}]}]
[ocpp  ] TRACE 2024/08/28 08:08:46 send SN10052307203612: [3,"16",{}]
[ocpp  ] TRACE 2024/08/28 08:08:55 send SN10052307203612: [2,"1627595517","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:08:55 recv SN10052307203612: [3,"1627595517",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:08:56 recv SN10052307203612: [2,"17","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-27T22:08:54Z","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.3","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"}]}]}]
[ocpp  ] TRACE 2024/08/28 08:08:56 send SN10052307203612: [3,"17",{}]
[site  ] DEBUG 2024/08/28 08:08:58 ----
[lp-1  ] DEBUG 2024/08/28 08:08:58 charge power: 0W
[lp-1  ] DEBUG 2024/08/28 08:08:58 charge currents: [0 0 0]A
[circuit-main] DEBUG 2024/08/28 08:08:58 power: 496.48W
[circuit-main] DEBUG 2024/08/28 08:08:58 current: 0A
[site  ] DEBUG 2024/08/28 08:08:58 pv power: 841W
[site  ] DEBUG 2024/08/28 08:08:58 grid meter: 496W
[site  ] DEBUG 2024/08/28 08:08:58 site power: 496W
[lp-1  ] DEBUG 2024/08/28 08:08:58 charge voltages: [245 0 0]V
[lp-1  ] DEBUG 2024/08/28 08:08:58 detected connected phases: 1p
[lp-1  ] DEBUG 2024/08/28 08:08:58 charge total import: 0.000kWh
[lp-1  ] DEBUG 2024/08/28 08:08:58 charger status: B
[lp-1  ] DEBUG 2024/08/28 08:08:58 charge start energy: 0.000kWh
[lp-1  ] INFO 2024/08/28 08:08:58 car connected
[lp-1  ] DEBUG 2024/08/28 08:08:58 pv timer elapse
[lp-1  ] DEBUG 2024/08/28 08:08:58 pv timer inactive
[lp-1  ] DEBUG 2024/08/28 08:08:58 charge total import: 0.000kWh
[lp-1  ] DEBUG 2024/08/28 08:08:58 charger vehicle id: evcc
[lp-1  ] DEBUG 2024/08/28 08:08:58 vehicle status: A
[lp-1  ] DEBUG 2024/08/28 08:08:58 vehicle soc: 83%
[lp-1  ] DEBUG 2024/08/28 08:08:58 vehicle range: 335km
[lp-1  ] DEBUG 2024/08/28 08:08:58 pv charge current: 0A = 0A + -2.16A (496W @ 1p)
[lp-1  ] DEBUG 2024/08/28 08:08:58 pv enable timer reset
[lp-1  ] DEBUG 2024/08/28 08:08:58 pv timer inactive
[ocpp  ] TRACE 2024/08/28 08:09:04 recv SN10052307203612: [2,"18","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/28 08:09:04 send SN10052307203612: [3,"18",{"currentTime":"2024-08-27T22:09:04Z"}]
[ocpp  ] TRACE 2024/08/28 08:09:05 send SN10052307203612: [2,"3269614396","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 08:09:05 recv SN10052307203612: [3,"3269614396",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 08:09:05 recv SN10052307203612: [2,"19","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-27T22:09:04Z","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.3","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"}]}]}]
[ocpp  ] TRACE 2024/08/28 08:09:05 send SN10052307203612: [3,"19",{}]
[ocpp  ] TRACE 2024/08/28 08:09:06 recv SN10052307203612: [2,"20","StatusNotification",{"connectorId":1,"status":"Finishing","errorCode":"NoError","timestamp":"2024-08-27T22:09:03Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/28 08:09:06 send SN10052307203612: [3,"20",{}]

What type of operating system are you running?

Linux

Version

0.130.6
@premultiply
Copy link
Member

The final reason why it jumps to "Finishing" state is an internal timeout of the charger waiting for a transaction to be started after vehicle connection (Available->Preparing) either by the user (RFID or Autostart) or by CS (RemoteStartTransaction).

remotestart: true is set here so evcc SHOULD have started a new transaction but did not do this, at least not in time.

So we need to find out why. Charger signaling itself looks good so far.

What have you set for interval in evcc configuration?

This value might be just too large for the internal transaction timeout trap.

Can you please try to reduce it to interval: 10s and try again?

Additionally please attach the output of evcc charger --diagnose so that we can see all internal configuration settings of the charger.

@premultiply premultiply self-assigned this Aug 28, 2024
@jeffborg
Copy link
Contributor Author

@premultiply interval was set to 30 seconds, Found 10 seconds was too bouncy but this was quite some time ago when I did the initial setup. I have changed it to 10 can see how this goes tomorrow.

Here is the output from evcc charger --diagnose just seems to be the same as the normal trace ocpp level.

/app # evcc charger --diagnose --config /config/evcc-min.yaml
[main  ] INFO 2024/08/28 15:18:56 evcc 0.130.6
[main  ] INFO 2024/08/28 15:18:56 using config file: /config/evcc-min.yaml
[db    ] INFO 2024/08/28 15:18:56 using sqlite database: /config/.evcc/evcc.db





[ocpp-1] DEBUG 2024/08/28 15:18:58 waiting for chargepoint: 5m0s

[ocpp  ] INFO 2024/08/28 15:19:25 charge point connected, registering: SN10052307203612
[ocpp  ] TRACE 2024/08/28 15:19:25 recv SN10052307203612: [2,"1755","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/28 15:19:25 send SN10052307203612: [3,"1755",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:26 send SN10052307203612: [2,"1221330925","ChangeAvailability",{"connectorId":0,"type":"Operative"}]
[ocpp  ] TRACE 2024/08/28 15:19:26 recv SN10052307203612: [3,"1221330925",{"status":"Rejected"}]
[ocpp-1] DEBUG 2024/08/28 15:19:26 failed configuring availability: Rejected
[ocpp  ] TRACE 2024/08/28 15:19:26 send SN10052307203612: [2,"2840335103","GetConfiguration",{}]
[ocpp  ] TRACE 2024/08/28 15:19:27 recv SN10052307203612: [3,"2840335103",{"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/28 15:19:27 send SN10052307203612: [2,"1375653863","TriggerMessage",{"requestedMessage":"BootNotification"}]
[ocpp  ] TRACE 2024/08/28 15:19:28 recv SN10052307203612: [3,"1375653863",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:28 recv SN10052307203612: [2,"1756","BootNotification",{"chargePointModel":"23","chargePointSerialNumber":"SN10052307203612","chargePointVendor":"EN+","firmwareVersion":"1.3.53"}]
[ocpp  ] TRACE 2024/08/28 15:19:28 send SN10052307203612: [3,"1756",{"currentTime":"2024-08-28T05:19:28Z","interval":60,"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:28 send SN10052307203612: [2,"3404582700","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Power.Active.Import"}]
[ocpp  ] TRACE 2024/08/28 15:19:29 recv SN10052307203612: [3,"3404582700",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:29 send SN10052307203612: [2,"388918331","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Energy.Active.Import.Register"}]
[ocpp  ] TRACE 2024/08/28 15:19:30 recv SN10052307203612: [3,"388918331",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:30 send SN10052307203612: [2,"3222215565","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Current.Import"}]
[ocpp  ] TRACE 2024/08/28 15:19:31 recv SN10052307203612: [3,"3222215565",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:31 send SN10052307203612: [2,"4278702060","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Voltage"}]
[ocpp  ] TRACE 2024/08/28 15:19:32 recv SN10052307203612: [3,"4278702060",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:32 send SN10052307203612: [2,"4116330917","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Current.Offered"}]
[ocpp  ] TRACE 2024/08/28 15:19:33 recv SN10052307203612: [3,"4116330917",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:33 send SN10052307203612: [2,"90516525","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Power.Offered"}]
[ocpp  ] TRACE 2024/08/28 15:19:34 recv SN10052307203612: [3,"90516525",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:34 send SN10052307203612: [2,"963232952","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"SoC"}]
[ocpp  ] TRACE 2024/08/28 15:19:35 recv SN10052307203612: [3,"963232952",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:35 send SN10052307203612: [2,"3371392617","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Power.Active.Import,Energy.Active.Import.Register,Current.Import,Voltage,Current.Offered,Power.Offered,SoC"}]
[ocpp  ] TRACE 2024/08/28 15:19:35 recv SN10052307203612: [2,"1757","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/28 15:19:35 send SN10052307203612: [3,"1757",{"currentTime":"2024-08-28T05:19:35Z"}]
[ocpp  ] TRACE 2024/08/28 15:19:35 recv SN10052307203612: [3,"3371392617",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:36 send SN10052307203612: [2,"1424903409","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:19:36 recv SN10052307203612: [3,"1424903409",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:36 recv SN10052307203612: [2,"1758","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:19: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:19:36 send SN10052307203612: [2,"1334074813","ChangeConfiguration",{"key":"MeterValueSampleInterval","value":"10"}]
[ocpp  ] TRACE 2024/08/28 15:19:36 send SN10052307203612: [3,"1758",{}]
[ocpp  ] TRACE 2024/08/28 15:19:37 recv SN10052307203612: [3,"1334074813",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:37 send SN10052307203612: [2,"1992258850","ChangeConfiguration",{"key":"WebSocketPingInterval","value":"30"}]
[ocpp  ] TRACE 2024/08/28 15:19:37 recv SN10052307203612: [3,"1992258850",{"status":"NotSupported"}]
[ocpp  ] TRACE 2024/08/28 15:19:45 send SN10052307203612: [2,"1527470529","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:19:45 recv SN10052307203612: [3,"1527470529",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:46 recv SN10052307203612: [2,"1759","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:19:45Z","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":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:19:46 send SN10052307203612: [3,"1759",{}]
[ocpp  ] TRACE 2024/08/28 15:19:55 send SN10052307203612: [2,"1917955538","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:19:56 recv SN10052307203612: [3,"1917955538",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:19:56 recv SN10052307203612: [2,"1760","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:19: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:19:56 send SN10052307203612: [3,"1760",{}]
[ocpp  ] TRACE 2024/08/28 15:20:05 send SN10052307203612: [2,"112530555","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:20:05 recv SN10052307203612: [3,"112530555",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:20:06 recv SN10052307203612: [2,"1761","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:20:05Z","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":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:20:06 send SN10052307203612: [3,"1761",{}]
[ocpp  ] TRACE 2024/08/28 15:20:15 send SN10052307203612: [2,"2973881994","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:20:16 recv SN10052307203612: [3,"2973881994",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:20:16 recv SN10052307203612: [2,"1762","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:20: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:20:16 send SN10052307203612: [3,"1762",{}]
[ocpp  ] TRACE 2024/08/28 15:20:25 send SN10052307203612: [2,"3547858010","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:20:26 recv SN10052307203612: [3,"3547858010",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:20:26 recv SN10052307203612: [2,"1763","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:20:24Z","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":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:20:26 send SN10052307203612: [3,"1763",{}]
[ocpp  ] TRACE 2024/08/28 15:20:33 recv SN10052307203612: [2,"1764","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/28 15:20:33 send SN10052307203612: [3,"1764",{"currentTime":"2024-08-28T05:20:33Z"}]
[ocpp  ] TRACE 2024/08/28 15:20:35 send SN10052307203612: [2,"3259047576","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:20:36 recv SN10052307203612: [3,"3259047576",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:20:36 recv SN10052307203612: [2,"1765","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:20: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:20:36 send SN10052307203612: [3,"1765",{}]
[ocpp  ] TRACE 2024/08/28 15:20:45 send SN10052307203612: [2,"1241150825","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:20:46 recv SN10052307203612: [3,"1241150825",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:20:46 recv SN10052307203612: [2,"1766","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:20:45Z","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":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:20:46 send SN10052307203612: [3,"1766",{}]
[ocpp  ] TRACE 2024/08/28 15:20:55 send SN10052307203612: [2,"1488313405","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:20:55 recv SN10052307203612: [3,"1488313405",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:20:56 recv SN10052307203612: [2,"1767","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:20: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:20:56 send SN10052307203612: [3,"1767",{}]
[ocpp  ] TRACE 2024/08/28 15:21:05 send SN10052307203612: [2,"2454615843","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:21:05 recv SN10052307203612: [3,"2454615843",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:21:06 recv SN10052307203612: [2,"1768","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:21:05Z","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":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:21:06 send SN10052307203612: [3,"1768",{}]
[ocpp  ] TRACE 2024/08/28 15:21:15 send SN10052307203612: [2,"3035279807","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:21:16 recv SN10052307203612: [3,"3035279807",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:21:16 recv SN10052307203612: [2,"1769","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:21: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:21:16 send SN10052307203612: [3,"1769",{}]
[ocpp  ] TRACE 2024/08/28 15:21:25 send SN10052307203612: [2,"2574034110","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:21:25 recv SN10052307203612: [3,"2574034110",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:21:26 recv SN10052307203612: [2,"1770","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:21: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":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:21:26 send SN10052307203612: [3,"1770",{}]
[ocpp  ] TRACE 2024/08/28 15:21:33 recv SN10052307203612: [2,"1771","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/28 15:21:33 send SN10052307203612: [3,"1771",{"currentTime":"2024-08-28T05:21:33Z"}]
[ocpp  ] TRACE 2024/08/28 15:21:35 send SN10052307203612: [2,"1621292284","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/28 15:21:36 recv SN10052307203612: [3,"1621292284",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/28 15:21:36 recv SN10052307203612: [2,"1772","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T05:21: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"2518.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":4}]
[ocpp  ] TRACE 2024/08/28 15:21:36 send SN10052307203612: [3,"1772",{}]
[main  ] FATAL 2024/08/28 15:21:37 cannot create charger 'solax': cannot create charger type 'template': cannot create charger type 'ocpp': timeout

@andig andig added the question Rather clarification than issue label Aug 28, 2024
@jeffborg
Copy link
Contributor Author

So tomorrow morning,
Usual things
1 - because ocpp channel is too chatty see all my other bug reports and it was far less chatty in 0.129.0 the evse of course is disconnected
2 - just power cycled the evse, no unplug replug but only change is the interval set to 10 seconds from yesterday so I think evcc sent the messages faster than the evse deciding to timeout and ending in finishing state

The only thing in evcc is showing waiting for Authorisation which is a bit weird it's working!

image

Here is the logs of the evse connecting something might be in here to show why it's saying asking for auth but of course it's charging.

The other weird thing is why is the evse sending the MeterValues and DataTransfer messages twice every 10 seconds. if somehow this wasn't doubled up it may help the issue of the evse deciding to disconnect and being very unstable from 0.130 onwards

[ocpp  ] DEBUG 2024/08/29 08:49:32 charge point connected: SN10052307203612
[ocpp  ] TRACE 2024/08/29 08:49:32 send SN10052307203612: [2,"1159558247","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/29 08:49:32 recv SN10052307203612: [3,"1159558247",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/29 08:49:34 send SN10052307203612: [2,"2717001353","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/29 08:49:34 recv SN10052307203612: [3,"2717001353",{"status":"Accepted"}]
[site  ] DEBUG 2024/08/29 08:49:35 ----
[lp-1  ] ERROR 2024/08/29 08:49:35 charge power: timeout
[ocpp  ] TRACE 2024/08/29 08:49:36 send SN10052307203612: [2,"1015783284","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[lp-1  ] ERROR 2024/08/29 08:49:36 charge currents: timeout
[circuit-main] DEBUG 2024/08/29 08:49:36 power: -527.9W
[circuit-main] DEBUG 2024/08/29 08:49:36 current: 0A
[site  ] DEBUG 2024/08/29 08:49:36 pv power: 1806W
[site  ] DEBUG 2024/08/29 08:49:36 grid meter: -528W
[site  ] DEBUG 2024/08/29 08:49:36 site power: -528W
[lp-1  ] ERROR 2024/08/29 08:49:36 charge voltages: timeout
[lp-1  ] ERROR 2024/08/29 08:49:36 charge total import: timeout
[lp-1  ] DEBUG 2024/08/29 08:49:36 charger status: B
[lp-1  ] DEBUG 2024/08/29 08:49:36 pv charge current: min 6A > 2.3A (-528W @ 1p, battery: false)
[ocpp  ] TRACE 2024/08/29 08:49:36 recv SN10052307203612: [3,"1015783284",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/29 08:49:36 send SN10052307203612: [2,"1537393320","SetChargingProfile",{"connectorId":1,"csChargingProfiles":{"chargingProfileId":3,"stackLevel":5,"chargingProfilePurpose":"TxDefaultProfile","chargingProfileKind":"Absolute","chargingSchedule":{"startSchedule":"2024-08-28T22:49:36Z","chargingRateUnit":"A","chargingSchedulePeriod":[{"startPeriod":0,"limit":6}]}}}]
[ocpp  ] TRACE 2024/08/29 08:49:37 recv SN10052307203612: [3,"1537393320",{"status":"Accepted"}]
[lp-1  ] DEBUG 2024/08/29 08:49:37 max charge current: 6A
[ocpp  ] TRACE 2024/08/29 08:49:37 send SN10052307203612: [2,"276879150","SetChargingProfile",{"connectorId":1,"csChargingProfiles":{"chargingProfileId":3,"stackLevel":5,"chargingProfilePurpose":"TxDefaultProfile","chargingProfileKind":"Absolute","chargingSchedule":{"startSchedule":"2024-08-28T22:49:37Z","chargingRateUnit":"A","chargingSchedulePeriod":[{"startPeriod":0,"limit":6}]}}}]
[ocpp  ] TRACE 2024/08/29 08:49:38 recv SN10052307203612: [3,"276879150",{"status":"Accepted"}]
[lp-1  ] DEBUG 2024/08/29 08:49:38 charger enable
[ocpp  ] TRACE 2024/08/29 08:49:38 send SN10052307203612: [2,"1076851147","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[lp-1  ] DEBUG 2024/08/29 08:49:38 wake-up timer: start
[ocpp  ] TRACE 2024/08/29 08:49:39 recv SN10052307203612: [3,"1076851147",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/29 08:49:40 send SN10052307203612: [2,"3495524807","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/29 08:49:40 recv SN10052307203612: [3,"3495524807",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/29 08:49:40 recv SN10052307203612: [2,"7","BootNotification",{"chargePointModel":"23","chargePointSerialNumber":"SN10052307203612","chargePointVendor":"EN+","firmwareVersion":"1.3.53"}]
[ocpp  ] TRACE 2024/08/29 08:49:40 send SN10052307203612: [3,"7",{"currentTime":"2024-08-28T22:49:40Z","interval":60,"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/29 08:49:41 recv SN10052307203612: [2,"8","DataTransfer",{"vendorId":"EN+","messageId":"gatewayInfo","data":"{\"SN\":\"SN10052307203612\",\"fwVer\":\"V43.0.552\",\"gateCode\":107,\"measureType\":1,\"disconnectReason\":4,\"maxCurr\":320,\"phase\":1,\"sdCardStatus\":0}"}]
[ocpp  ] TRACE 2024/08/29 08:49:41 send SN10052307203612: [3,"8",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/29 08:49:41 recv SN10052307203612: [2,"offline-1","StopTransaction",{"meterStop":32,"transactionId":2,"transactionData":[{"sampledValue":[{"measurand":"Energy.Active.Import.Register","context":"Transaction.End","value":"0.032","unit":"kWh"}],"timestamp":"2024-08-28T22:48:24Z"}],"timestamp":"2024-08-28T22:48:24Z","idTag":"evcc","reason":"SoftReset"}]
[ocpp  ] TRACE 2024/08/29 08:49:41 send SN10052307203612: [3,"offline-1",{"idTagInfo":{"status":"Accepted"}}]
[ocpp  ] TRACE 2024/08/29 08:49:42 recv SN10052307203612: [2,"9","StatusNotification",{"connectorId":0,"status":"Preparing","errorCode":"NoError","timestamp":"2024-08-28T22:49:41Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/29 08:49:42 send SN10052307203612: [3,"9",{}]
[ocpp  ] TRACE 2024/08/29 08:49:42 recv SN10052307203612: [2,"10","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/29 08:49:42 send SN10052307203612: [3,"10",{"currentTime":"2024-08-28T22:49:42Z"}]
[ocpp  ] TRACE 2024/08/29 08:49:43 recv SN10052307203612: [2,"11","StatusNotification",{"connectorId":1,"status":"Preparing","errorCode":"NoError","timestamp":"2024-08-28T22:49:41Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/29 08:49:43 send SN10052307203612: [3,"11",{}]
[ocpp  ] TRACE 2024/08/29 08:49:43 recv SN10052307203612: [2,"offline-6","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"1970-01-01T00:00: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":"0.0","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":2}]
[ocpp  ] TRACE 2024/08/29 08:49:43 send SN10052307203612: [3,"offline-6",{}]
[site  ] DEBUG 2024/08/29 08:49:45 ----
[lp-1  ] DEBUG 2024/08/29 08:49:45 charge power: 0W
[lp-1  ] DEBUG 2024/08/29 08:49:45 charge currents: [0 0 0]A
[circuit-main] DEBUG 2024/08/29 08:49:45 power: -514.3W
[circuit-main] DEBUG 2024/08/29 08:49:45 current: 0A
[site  ] DEBUG 2024/08/29 08:49:45 pv power: 1810W
[site  ] DEBUG 2024/08/29 08:49:45 grid meter: -514W
[site  ] DEBUG 2024/08/29 08:49:45 site power: -514W
[lp-1  ] DEBUG 2024/08/29 08:49:45 charge voltages: [247 0 0]V
[lp-1  ] DEBUG 2024/08/29 08:49:45 detected connected phases: 1p
[lp-1  ] DEBUG 2024/08/29 08:49:45 charge total import: 0.032kWh
[ocpp  ] TRACE 2024/08/29 08:49:45 send SN10052307203612: [2,"2767437662","RemoteStartTransaction",{"connectorId":1,"idTag":"evcc"}]
[ocpp  ] TRACE 2024/08/29 08:49:46 recv SN10052307203612: [3,"2767437662",{"status":"Accepted"}]
[lp-1  ] DEBUG 2024/08/29 08:49:46 charger status: B
[lp-1  ] DEBUG 2024/08/29 08:49:46 pv charge current: min 6A > 2.24A (-514W @ 1p, battery: false)
[ocpp  ] TRACE 2024/08/29 08:49:47 recv SN10052307203612: [2,"12","StartTransaction",{"connectorId":1,"idTag":"evcc","meterStart":0,"timestamp":"2024-08-28T22:49:44Z"}]
[ocpp  ] TRACE 2024/08/29 08:49:47 send SN10052307203612: [3,"12",{"idTagInfo":{"status":"Accepted"},"transactionId":3}]
[ocpp  ] TRACE 2024/08/29 08:49:48 recv SN10052307203612: [2,"13","StatusNotification",{"connectorId":1,"status":"SuspendedEV","errorCode":"NoError","timestamp":"2024-08-28T22:49:45Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/29 08:49:48 send SN10052307203612: [3,"13",{}]
[ocpp  ] TRACE 2024/08/29 08:49:48 recv SN10052307203612: [2,"14","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T22:49:46Z","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":"245.8","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"0.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Sample.Periodic"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/29 08:49:48 send SN10052307203612: [3,"14",{}]
[ocpp  ] TRACE 2024/08/29 08:49:49 recv SN10052307203612: [2,"15","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":925,\"transactionId\":3,\"totalPowerFactor\":256,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":0,\"ccVolt\":256,\"offset0\":2015,\"offset1\":2018,\"leakcurr\":0,\"AMBTemp\":275,\"lockStatus\":1,\"ctCurrent\":9}"}]
[ocpp  ] TRACE 2024/08/29 08:49:49 send SN10052307203612: [3,"15",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/29 08:49:51 recv SN10052307203612: [2,"16","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T22:49:50Z","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":"245.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"0.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Sample.Periodic"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/29 08:49:51 send SN10052307203612: [3,"16",{}]
[ocpp  ] TRACE 2024/08/29 08:49:52 recv SN10052307203612: [2,"17","StatusNotification",{"connectorId":1,"status":"Charging","errorCode":"NoError","timestamp":"2024-08-28T22:49:49Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/29 08:49:52 send SN10052307203612: [3,"17",{}]
[ocpp  ] TRACE 2024/08/29 08:49:52 recv SN10052307203612: [2,"18","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":612,\"transactionId\":3,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2015,\"offset1\":2018,\"leakcurr\":0,\"AMBTemp\":275,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/08/29 08:49:52 send SN10052307203612: [3,"18",{"status":"Accepted"}]
[site  ] DEBUG 2024/08/29 08:49:55 ----
[lp-1  ] DEBUG 2024/08/29 08:49:55 charge power: 0W
[lp-1  ] DEBUG 2024/08/29 08:49:55 charge currents: [0 0 0]A
[circuit-main] DEBUG 2024/08/29 08:49:55 power: -596.83W
[circuit-main] DEBUG 2024/08/29 08:49:55 current: 0A
[site  ] DEBUG 2024/08/29 08:49:55 pv power: 1809W
[site  ] DEBUG 2024/08/29 08:49:55 grid meter: -597W
[site  ] DEBUG 2024/08/29 08:49:55 site power: -597W
[lp-1  ] DEBUG 2024/08/29 08:49:55 charge voltages: [245 0 0]V
[lp-1  ] DEBUG 2024/08/29 08:49:55 detected connected phases: 1p
[lp-1  ] DEBUG 2024/08/29 08:49:55 charge total import: 0.000kWh
[lp-1  ] DEBUG 2024/08/29 08:49:55 charger status: C
[lp-1  ] DEBUG 2024/08/29 08:49:55 charge start energy: 0.000kWh
[lp-1  ] INFO 2024/08/29 08:49:55 start charging ->
[lp-1  ] DEBUG 2024/08/29 08:49:55 wake-up timer: stop
[lp-1  ] DEBUG 2024/08/29 08:49:55 charger vehicle id: evcc
[lp-1  ] DEBUG 2024/08/29 08:49:55 soc estimated: 48.00% (vehicle: 48.00%)
[lp-1  ] DEBUG 2024/08/29 08:49:55 vehicle soc: 48%
[lp-1  ] DEBUG 2024/08/29 08:49:55 vehicle range: 201km
[lp-1  ] DEBUG 2024/08/29 08:49:55 pv charge current: min 6A > 4.59A (-597W @ 1p, battery: false)
[ocpp  ] TRACE 2024/08/29 08:49:58 recv SN10052307203612: [2,"19","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T22:49:56Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.6"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"245.6","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"0.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"152.0","context":"Sample.Periodic"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/29 08:49:58 send SN10052307203612: [3,"19",{}]
[ocpp  ] TRACE 2024/08/29 08:49:59 recv SN10052307203612: [2,"20","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":3,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2015,\"offset1\":2018,\"leakcurr\":10,\"AMBTemp\":275,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2024/08/29 08:49:59 send SN10052307203612: [3,"20",{"status":"Accepted"}]
[site  ] DEBUG 2024/08/29 08:50:05 ----
[lp-1  ] DEBUG 2024/08/29 08:50:05 charge power: 152W
[lp-1  ] DEBUG 2024/08/29 08:50:05 charge currents: [0.6 0 0]A
[circuit-main] DEBUG 2024/08/29 08:50:05 power: 847.59W
[circuit-main] DEBUG 2024/08/29 08:50:05 current: 0A
[site  ] DEBUG 2024/08/29 08:50:05 pv power: 1819W
[site  ] DEBUG 2024/08/29 08:50:05 grid meter: 848W
[site  ] DEBUG 2024/08/29 08:50:05 site power: 848W
[lp-1  ] DEBUG 2024/08/29 08:50:05 charge voltages: [246 0 0]V
[lp-1  ] DEBUG 2024/08/29 08:50:05 detected connected phases: 1p
[lp-1  ] DEBUG 2024/08/29 08:50:05 charge total import: 0.000kWh
[lp-1  ] DEBUG 2024/08/29 08:50:05 charger status: C
[lp-1  ] DEBUG 2024/08/29 08:50:05 soc estimated: 48.00% (vehicle: 48.00%)
[lp-1  ] DEBUG 2024/08/29 08:50:05 vehicle soc: 48%
[lp-1  ] DEBUG 2024/08/29 08:50:05 vehicle range: 201km
[lp-1  ] DEBUG 2024/08/29 08:50:05 pv charge current: min 6A > 0A (848W @ 1p, battery: false)
[ocpp  ] TRACE 2024/08/29 08:50:06 send SN10052307203612: [2,"1785123224","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/29 08:50:06 recv SN10052307203612: [3,"1785123224",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/29 08:50:07 recv SN10052307203612: [2,"21","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T22:50:05Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.6"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"245.6","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"0.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"152.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/29 08:50:07 send SN10052307203612: [3,"21",{}]
[ocpp  ] TRACE 2024/08/29 08:50:07 recv SN10052307203612: [2,"22","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":3,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2015,\"offset1\":2018,\"leakcurr\":20,\"AMBTemp\":275,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/08/29 08:50:07 send SN10052307203612: [3,"22",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/29 08:50:08 recv SN10052307203612: [2,"23","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-28T22:50:07Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"0.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1504.0","context":"Sample.Periodic"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/29 08:50:08 send SN10052307203612: [3,"23",{}]
[ocpp  ] TRACE 2024/08/29 08:50:09 recv SN10052307203612: [2,"24","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":3,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2015,\"offset1\":2018,\"leakcurr\":20,\"AMBTemp\":275,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/08/29 08:50:09 send SN10052307203612: [3,"24",{"status":"Accepted"}]
[site  ] DEBUG 2024/08/29 08:50:15 ----
[lp-1  ] DEBUG 2024/08/29 08:50:15 charge power: 1504W

@jeffborg
Copy link
Contributor Author

Correction on the 10 second interval, evcc is too bouncy once solar was above the minimum 6amps, will try 15 seconds

restart evcc with interval setting changed and back to square 1 had to do the following to get working again

  • turn off evse
  • unplug car
  • restart evcc
  • turn on evse
  • wait till evcc said disconnected don't plug in car too quick
  • plug in car

Also evcc has a warning about interval less and 30 seconds can lead to issues, 30 seconds worked pretty well it didn't need to be any quicker, the ocpp stuff shouldn't be doing commands for connection etc.. on the interval schedule.

Or could just go back to 0.129.0 and it was perfect 🤷

@premultiply
Copy link
Member

So is it working now with the reduced interval or not?

Log looks fine to me now.

@premultiply premultiply changed the title OCPP: evcc unable to charge wants to unplug replug car always (EN+ firmware) EN+: "Preparing" timeout before RemoteStartTransaction could be sent on long intervals Aug 29, 2024
@jeffborg
Copy link
Contributor Author

jeffborg commented Aug 29, 2024 •
edited
Loading

No I had to do the whole 6 step process in previous comment to get it working again and that is with a 15 second interval.

Knowing what I know a guess to fix this would be upon preparing immediately just put charger into the suspended EVSE state. Then the usual interval should enable it if required. Don't wait for the interval loop to do that.

@jeffborg
Copy link
Contributor Author

Today the interval at 15 seconds I'm getting

[lp-1 ] WARN 2024/08/30 13:43:18 charger logic error: current mismatch (got 16.2A measured, expected 14A)

it's oscillating because the interval is really too quick to get stable power measurements between the evse grid meter and solar inverter and if a household load switches on/off and a major adjustment is required in the evse for solar tracking this occurs.

Lowering the interval to fix this isn't really a solution

@andig
Copy link
Member

andig commented Aug 30, 2024

evcc is too bouncy once solar was above the minimum 6amps, will try 15 seconds

Sounds like your car consumes reactive power.

Lowering the interval to fix this isn't really a solution

It shouldn't be. In this case: please contact vendor how charger can start session if it can't be started immediately.

Also: it's totally unclear to me why this should have worked on 129. We were also using RemoteStart then and only did this in the regular intervals.

Today the interval at 15 seconds I'm getting

The log doesn't show any of that.

What it shows is this, so there is still a different issue that needs be fixed:

charge power: timeout

@andig
Copy link
Member

andig commented Aug 30, 2024

Since there are more problems with this charger that do not seem contributable to evcc (#15552) I'm closing here. If there's an actual issue with evcc feel free to reopen.

@andig andig closed this as completed Aug 30, 2024
@premultiply premultiply mentioned this issue Aug 30, 2024
Merged
@jeffborg
Copy link
Contributor Author

@andig there were a lot less problems before 0.130.0. I have gone back to 0.129.0 just turn EVSE on off and it started working no funny unplugging car etc required.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@premultiply premultiply

Labels
question Rather clarification than issue
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@andig @jeffborg @premultiply