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

EvseMode: Car closes the connection after AppProtocolResponse #9

Closed
uhi22 opened this issue Jun 27, 2023 · 6 comments
Closed

EvseMode: Car closes the connection after AppProtocolResponse #9

uhi22 opened this issue Jun 27, 2023 · 6 comments

Comments

@uhi22
Copy link
Owner

uhi22 commented Jun 27, 2023 •
edited
Loading

Start of the discussion was here: #6 (comment)

Cross references:
SmartEVSE/SmartEVSE-3#25
SmartEVSE/SmartEVSE-3#25 (comment)

Problem description: After successful SLAC, SDP and protocol handshake, the car closes the TCP connection. This happens stable with the BMW iX3. No problem with Tesla and Hyundai Ioniq.

Root cause: The openV2Gx sets the supportedAppProtocolRes.SchemaID = 1, because we assume, that the car sends a list of supported schemas where the first enty is DIN and has SchemaID 1. The iX3 sends the (one-and-only) DIN schema entry with SchemaID = 0 (which is allowed), and closes the connection after the pyPlc selects the SchemaID 1.

Clean solution would be, that the pyPlc parses the list of announced schemas, selects the entry with DIN, and sends back the SchemaID of that entry back to the car, no matter which value it is.
Potential workaround could be, to send no SchemaID at all. The SchemaID is an optional field, and in best case the vehicle is also happy without it. There is the risk, that some car types will accept this, maybe others not.
@uhi22 uhi22 mentioned this issue Jun 27, 2023
Closed
@uhi22
Copy link
Owner Author

uhi22 commented Jun 27, 2023

Progress Note:

  • Prepared the exi decoder/encoder, to that it provides the decoded protocolHandshakeRequest as json, and for the protocolhandshakeResponse accepts the SchemaID as command line parameter.
    uhi22/OpenV2Gx@68850c9
  • Prepared some EXI examples for testing the decoder cc2131d

Next step: adapt the fsmEvse, to run through the list of supported protocols, select the DIN, and respond with the assigned SchemaID.

uhi22 added a commit that referenced this issue Jun 27, 2023
@uhi22
fix: EvseMode: Find the DIN schema and use the related SchemaID. Shou…
2b5806b 
…ld fix issue #9
@uhi22
Copy link
Owner Author

uhi22 commented Jun 27, 2023 •
edited
Loading

Pushed the fix of fsmEvse. In best case, this should be the solution for the issue.
To test it, the following steps are necessary:

  • pull OpenV2Gx
  • compile OpenV2Gx
  • pull pyPLC

When running with the iX3, this should look like

[13641ms] [EVSE] In state WaitForSupportedApplicationProtocolRequest, received (42bytes) = 01 FE 80 01 00 00 00 22 80 00 DB AB 93 71 D3 23 4B 71 D1 B9 81 89 91 89 D1 91 81 89 91 D2 6B 9B 3A 23 2B 30 02 00 00 00 00 40
[13781ms] [EVSE] {
"msgName": "supportedAppProtocolReq",
"info": "34 bytes to convert",
"error": "",
"result": "Vehicle supports 1 protocols. ProtocolEntry#1 ProtocolNamespace=urn:din:70121:2012:MsgDef Version=2.0 SchemaID=0 Priority=1 ",
"schema": "appHandshake",
"AppProtocol_arrayLen": "1",
"NameSpace_0": "urn:din:70121:2012:MsgDef",
"Version_0": "2.0",
"SchemaID_0": "0",
"Priority_0": "1",
"debug": ""
}
[13781ms] [EVSE] The car supports 1 schemas.
[13797ms] [EVSE] The NameSpace urn:din:70121:2012:MsgDef has SchemaID 0
[13797ms] [EVSE] Detected DIN
[13844ms] [EVSE] responding (12bytes) = 01 FE 80 01 00 00 00 04 80 40 00 00

and for the Tesla

[17428ms] [EVSE] In state WaitForSupportedApplicationProtocolRequest, received (74bytes) = 01 FE 80 01 00 00 00 42 80 00 DB AB 93 71 D3 23 4B 71 D1 B9 81 89 91 89 D1 91 81 89 91 D2 6B 9B 3A 23 2B 30 02 00 00 04 04 01 B7 57 26 E3 A7 46 57 36 C6 13 A6 46 96 E3 A3 23 03 13 83 A4 D7 36 74 46 56 60 00 01 C0 10 00 80
[17506ms] [EVSE] {
"msgName": "supportedAppProtocolReq",
"info": "66 bytes to convert",
"error": "",
"result": "Vehicle supports 2 protocols. ProtocolEntry#1 ProtocolNamespace=urn:din:70121:2012:MsgDef Version=2.0 SchemaID=1 Priority=2 ProtocolEntry#2 ProtocolNamespace=urn:tesla:din:2018:MsgDef Version=0.7 SchemaID=2 Priority=1 ",
"schema": "appHandshake",
"AppProtocol_arrayLen": "2",
"NameSpace_0": "urn:din:70121:2012:MsgDef",
"Version_0": "2.0",
"SchemaID_0": "1",
"Priority_0": "2",
"NameSpace_1": "urn:tesla:din:2018:MsgDef",
"Version_1": "0.7",
"SchemaID_1": "2",
"Priority_1": "1",
"debug": ""
}
[17521ms] [EVSE] The car supports 2 schemas.
[17521ms] [EVSE] The NameSpace urn:din:70121:2012:MsgDef has SchemaID 1
[17521ms] [EVSE] The NameSpace urn:tesla:din:2018:MsgDef has SchemaID 2
[17521ms] [EVSE] Detected DIN
[17568ms] [EVSE] responding (12bytes) = 01 FE 80 01 00 00 00 04 80 40 00 40

@uhi22 uhi22 mentioned this issue Jun 27, 2023
Open
@fluppie
Copy link

fluppie commented Jun 27, 2023

Did the steps, but when wanting to start the service (I use these parameters: https://github.com/ArendJanKramer/SmartEVSE-3/blob/modem_docs/manual/Modem.md)

pyPlc.ini.txt

Jun 27 22:46:24 rpievse systemd[1]: Started pyPlc.
Jun 27 22:46:25 rpievse python3.9[8424]: starting in EVSE_MODE
Jun 27 22:46:25 rpievse python3.9[8424]: press Ctrl-C to exit
Jun 27 22:46:25 rpievse python3.9[8424]: initializing pyPlcWorker
Jun 27 22:46:25 rpievse python3.9[8424]: [addressManager] we have local MAC 7A:B7:CC:7C:F9:B9.
Jun 27 22:46:25 rpievse python3.9[8424]: [addressManager] Found 1 link-local IPv6 addresses.
Jun 27 22:46:25 rpievse python3.9[8424]: fe80::956e:4ee7:aeee:9b73
Jun 27 22:46:25 rpievse python3.9[8424]: [addressManager] Local IPv6 is fe80::956e:4ee7:aeee:9b73
Jun 27 22:46:25 rpievse python3.9[8424]: Linux interface is eth1
Jun 27 22:46:25 rpievse python3.9[8424]: [addressManager] will give local MAC 7A:B7:CC:7C:F9:B9
Jun 27 22:46:25 rpievse python3.9[8424]: [addressManager] will give local MAC 7A:B7:CC:7C:F9:B9
Jun 27 22:46:25 rpievse python3.9[8424]: pyPlcIpv6 started with ownMac 7A:B7:CC:7C:F9:B9
Jun 27 22:46:25 rpievse python3.9[8424]: [addressManager] will give local MAC 7A:B7:CC:7C:F9:B9
Jun 27 22:46:25 rpievse python3.9[8424]: udplog started with ownMac 7A:B7:CC:7C:F9:B9
Jun 27 22:46:25 rpievse python3.9[8424]: logging to UDP Syslog is enabled
Jun 27 22:46:25 rpievse python3.9[8424]: sniffer created at eth1
Jun 27 22:46:25 rpievse python3.9[8424]: [67ms] [HARDWAREINTERFACE] Auto detection of serial ports. Available serial ports:
Jun 27 22:46:25 rpievse python3.9[8424]: [82ms] [HARDWAREINTERFACE] ignoring /dev/ttyAMA0, because this is not an USB serial port
Jun 27 22:46:25 rpievse python3.9[8424]: [83ms] [HARDWAREINTERFACE] ERROR: No serial ports found. No hardware interaction possible.
Jun 27 22:46:25 rpievse python3.9[8424]: Traceback (most recent call last):
Jun 27 22:46:25 rpievse python3.9[8424]:   File "/home/pi/myprogs/pyPlc/evseNoGui.py", line 62, in <module>
Jun 27 22:46:25 rpievse python3.9[8424]:     worker=pyPlcWorker.pyPlcWorker(cbAddToTrace, cbShowStatus, myMode, 0, socStatusCallback)
Jun 27 22:46:25 rpievse python3.9[8424]:   File "/home/pi/myprogs/pyPlc/pyPlcWorker.py", line 34, in __init__
Jun 27 22:46:25 rpievse python3.9[8424]:     self.hardwareInterface = hardwareInterface.hardwareInterface(self.workerAddToTrace, self.showStatus)
Jun 27 22:46:25 rpievse python3.9[8424]:   File "/home/pi/myprogs/pyPlc/hardwareInterface.py", line 259, in __init__
Jun 27 22:46:25 rpievse python3.9[8424]:     self.initPorts()
Jun 27 22:46:25 rpievse python3.9[8424]:   File "/home/pi/myprogs/pyPlc/hardwareInterface.py", line 217, in initPorts
Jun 27 22:46:25 rpievse python3.9[8424]:     self.canbus = can.interface.Bus(bustype='socketcan', channel="can0", can_filters = filters)
Jun 27 22:46:25 rpievse python3.9[8424]: AttributeError: module 'can' has no attribute 'interface'
Jun 27 22:46:25 rpievse systemd[1]: pyPlc.service: Main process exited, code=exited, status=1/FAILURE
Jun 27 22:46:25 rpievse systemd[1]: pyPlc.service: Failed with result 'exit-code'.
Jun 27 22:46:25 rpievse systemd[1]: pyPlc.service: Consumed 1.015s CPU time.
Jun 27 22:46:25 rpievse systemd[1]: pyPlc.service: Scheduled restart job, restart counter is at 5.
Jun 27 22:46:25 rpievse systemd[1]: Stopped pyPlc.
Jun 27 22:46:25 rpievse systemd[1]: pyPlc.service: Consumed 1.015s CPU time.
Jun 27 22:46:25 rpievse systemd[1]: pyPlc.service: Start request repeated too quickly.
Jun 27 22:46:25 rpievse systemd[1]: pyPlc.service: Failed with result 'exit-code'.
Jun 27 22:46:25 rpievse systemd[1]: Failed to start pyPlc.

Also tried to install can
sudo python -m pip install --upgrade can
and
python -m pip install --upgrade can

@uhi22
Copy link
Owner Author

uhi22 commented Jun 28, 2023

This sounds like chademo is enabled in the ini file. Just replace it with "none".

@fluppie
Copy link

fluppie commented Jun 28, 2023

Ok cool, looks like we are on the right track :-). Only the energycapacity should be around 74 kWh netto or 80 kWh bruto.
I think ABRP is able to read this via the OBDLink CX dongle.

Jun 28 08:28:42 rpievse python3.9[2137]: [257521ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:28:45 rpievse python3.9[2137]: [260196ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:28:48 rpievse python3.9[2137]: [262873ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:28:49 rpievse python3.9[2137]: [264091ms] received SLAC_PARAM.REQ
Jun 28 08:28:49 rpievse python3.9[2137]: [addressManager] pev has MAC EC:65:CC:B6:85:DC
Jun 28 08:28:49 rpievse python3.9[2137]: [264092ms] [EVSE] transmitting CM_SLAC_PARAM.CNF
Jun 28 08:28:49 rpievse python3.9[2137]: [264338ms] received MNBC_SOUND.IND
Jun 28 08:28:49 rpievse python3.9[2137]: [264339ms] received MNBC_SOUND.IND
Jun 28 08:28:49 rpievse python3.9[2137]: [264422ms] received MNBC_SOUND.IND
Jun 28 08:28:49 rpievse python3.9[2137]: [264423ms] received MNBC_SOUND.IND
Jun 28 08:28:49 rpievse python3.9[2137]: [264424ms] received MNBC_SOUND.IND
Jun 28 08:28:49 rpievse python3.9[2137]: [264506ms] received MNBC_SOUND.IND
Jun 28 08:28:49 rpievse python3.9[2137]: [264507ms] received MNBC_SOUND.IND
Jun 28 08:28:49 rpievse python3.9[2137]: [264508ms] received MNBC_SOUND.IND
Jun 28 08:28:50 rpievse python3.9[2137]: [264590ms] received MNBC_SOUND.IND
Jun 28 08:28:50 rpievse python3.9[2137]: [264591ms] received MNBC_SOUND.IND
Jun 28 08:28:50 rpievse python3.9[2137]: [264592ms] [EVSE] transmitting ATTEN_CHAR.IND
Jun 28 08:28:50 rpievse python3.9[2137]: [265486ms] received SLAC_MATCH.REQ
Jun 28 08:28:50 rpievse python3.9[2137]: [265487ms] [EVSE] transmitting SLAC_MATCH.CNF
Jun 28 08:28:51 rpievse python3.9[2137]: [265569ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:28:53 rpievse python3.9[2137]: [268247ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:28:55 rpievse python3.9[2137]: [addressManager] pev has IP fe80:0000:0000:0000:ee65:ccff:feb6:85dc
Jun 28 08:28:55 rpievse python3.9[2137]: V2GTP (10bytes) = 01 FE 90 00 00 00 00 02 10 00
Jun 28 08:28:55 rpievse python3.9[2137]: Ok, this was a valid SDP request. We are the SECC. Sending SDP response.
Jun 28 08:28:55 rpievse python3.9[2137]: SDP payload (20bytes) = FE 80 00 00 00 00 00 00 DB 7C 36 04 B2 2B C7 CF 3B 0E 10 00
Jun 28 08:28:55 rpievse python3.9[2137]: V2Gframe (28bytes) = 01 FE 90 01 00 00 00 14 FE 80 00 00 00 00 00 00 DB 7C 36 04 B2 2B C7 CF                                                                                                         3B 0E 10 00
Jun 28 08:28:55 rpievse python3.9[2137]: [269877ms] Connection from ('fe80::ee65:ccff:feb6:85dc', 49153, 0, 4)
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 49153 to 15118 (34bytes) = 80 00 DB AB 93 71 D3 23 4B 71 D1 B9 81 89 91 8                                                                                                        9 D1 91 81 89 91 D2 6B 9B 3A 23 2B 30 02 00 00 00 00 40
Jun 28 08:28:55 rpievse python3.9[2137]: [269911ms] [EVSE] In state WaitForSupportedApplicationProtocolRequest, received (42bytes) =                                                                                                         01 FE 80 01 00 00 00 22 80 00 DB AB 93 71 D3 23 4B 71 D1 B9 81 89 91 89 D1 91 81 89 91 D2 6B 9B 3A 23 2B 30 02 00 00 00 00 40
Jun 28 08:28:55 rpievse python3.9[2137]: [269941ms] [EVSE] {
Jun 28 08:28:55 rpievse python3.9[2137]: "msgName": "supportedAppProtocolReq",
Jun 28 08:28:55 rpievse python3.9[2137]: "info": "34 bytes to convert",
Jun 28 08:28:55 rpievse python3.9[2137]: "error": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "result": "Vehicle supports 1 protocols. ProtocolEntry#1 ProtocolNamespace=urn:din:70121:201                                                                                                        2:MsgDef Version=2.0 SchemaID=0 Priority=1 ",
Jun 28 08:28:55 rpievse python3.9[2137]: "schema": "appHandshake",
Jun 28 08:28:55 rpievse python3.9[2137]: "AppProtocol_arrayLen": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "NameSpace_0": "urn:din:70121:2012:MsgDef",
Jun 28 08:28:55 rpievse python3.9[2137]: "Version_0": "2.0",
Jun 28 08:28:55 rpievse python3.9[2137]: "SchemaID_0": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "Priority_0": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "debug": ""
Jun 28 08:28:55 rpievse python3.9[2137]: }
Jun 28 08:28:55 rpievse python3.9[2137]: [269944ms] [EVSE] The car supports 1 schemas.
Jun 28 08:28:55 rpievse python3.9[2137]: [269945ms] [EVSE] The NameSpace urn:din:70121:2012:MsgDef has SchemaID 0
Jun 28 08:28:55 rpievse python3.9[2137]: [269946ms] [EVSE] Detected DIN
Jun 28 08:28:55 rpievse python3.9[2137]: [269960ms] [EVSE] responding (12bytes) = 01 FE 80 01 00 00 00 04 80 40 00 00
Jun 28 08:28:55 rpievse python3.9[2137]: [269962ms] [EVSE] from 0 entering 1
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49153 (4bytes) = 80 40 00 00
Jun 28 08:28:55 rpievse python3.9[2137]: [270021ms] [EVSE] In state WaitForSessionSetupRequest, received (22bytes) = 01 FE 80 01 00 0                                                                                                        0 00 0E 80 9A 00 40 11 D0 1B B1 97 32 DA 17 70 00
Jun 28 08:28:55 rpievse python3.9[2137]: [270041ms] [EVSE] {
Jun 28 08:28:55 rpievse python3.9[2137]: "msgName": "SessionSetupReq",
Jun 28 08:28:55 rpievse python3.9[2137]: "info": "14 bytes to convert",
Jun 28 08:28:55 rpievse python3.9[2137]: "error": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "result": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:28:55 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.SessionID": "00",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVCCID.bytesLen": "6",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVCCID": "ec65ccb685dc",
Jun 28 08:28:55 rpievse python3.9[2137]: "debug": "Line532"
Jun 28 08:28:55 rpievse python3.9[2137]: }
Jun 28 08:28:55 rpievse python3.9[2137]: [270056ms] [EVSE] responding (31bytes) = 01 FE 80 01 00 00 00 17 80 9A 02 00 40 80 C1 01 41                                                                                                         81 C2 11 E0 20 1D 69 68 C0 C0 C0 C0 C0 80
Jun 28 08:28:55 rpievse python3.9[2137]: [270057ms] [EVSE] responding (31bytes) = 01 FE 80 01 00 00 00 17 80 9A 02 00 40 80 C1 01 41                                                                                                         81 C2 11 E0 20 1D 69 68 C0 C0 C0 C0 C0 80
Jun 28 08:28:55 rpievse python3.9[2137]: [270058ms] [EVSE] from 1 entering 2
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 49153 to 15118 (14bytes) = 80 9A 00 40 11 D0 1B B1 97 32 DA 17 70 00
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49153 (23bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 E0 20 1D 6                                                                                                        9 68 C0 C0 C0 C0 C0 80
Jun 28 08:28:55 rpievse python3.9[2137]: [270121ms] [EVSE] In state WaitForServiceDiscoveryRequest, received (22bytes) = 01 FE 80 01                                                                                                         00 00 00 0E 80 9A 02 00 40 80 C1 01 41 81 C2 11 94 00
Jun 28 08:28:55 rpievse python3.9[2137]: [270133ms] [EVSE] {
Jun 28 08:28:55 rpievse python3.9[2137]: "msgName": "ServiceDiscoveryReq",
Jun 28 08:28:55 rpievse python3.9[2137]: "info": "14 bytes to convert",
Jun 28 08:28:55 rpievse python3.9[2137]: "error": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "result": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:28:55 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "ServiceCategory_isUsed": "True",
Jun 28 08:28:55 rpievse python3.9[2137]: "debug": "Line508"
Jun 28 08:28:55 rpievse python3.9[2137]: }
Jun 28 08:28:55 rpievse python3.9[2137]: [270148ms] [EVSE] responding (27bytes) = 01 FE 80 01 00 00 00 13 80 9A 02 00 40 80 C1 01 41                                                                                                         81 C2 11 A0 01 20 02 41 00 C4
Jun 28 08:28:55 rpievse python3.9[2137]: [270149ms] [EVSE] from 2 entering 3
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 49153 to 15118 (14bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 94 00
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49153 (19bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 A0 01 20 0                                                                                                        2 41 00 C4
Jun 28 08:28:55 rpievse python3.9[2137]: [270211ms] [EVSE] In state WaitForServicePaymentSelectionRequest, received (24bytes) = 01 FE                                                                                                         80 01 00 00 00 10 80 9A 02 00 40 80 C1 01 41 81 C2 11 B2 00 12 80
Jun 28 08:28:55 rpievse python3.9[2137]: [270223ms] [EVSE] {
Jun 28 08:28:55 rpievse python3.9[2137]: "msgName": "ServicePaymentSelectionReq",
Jun 28 08:28:55 rpievse python3.9[2137]: "info": "16 bytes to convert",
Jun 28 08:28:55 rpievse python3.9[2137]: "error": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "result": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:28:55 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "SelectedPaymentOption": "ExternalPayment",
Jun 28 08:28:55 rpievse python3.9[2137]: "SelectedService.arrayLen": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "SelectedService.array": "0:1",
Jun 28 08:28:55 rpievse python3.9[2137]: "debug": "Line520"
Jun 28 08:28:55 rpievse python3.9[2137]: }
Jun 28 08:28:55 rpievse python3.9[2137]: [270238ms] [EVSE] responding (22bytes) = 01 FE 80 01 00 00 00 0E 80 9A 02 00 40 80 C1 01 41                                                                                                         81 C2 11 C0 00
Jun 28 08:28:55 rpievse python3.9[2137]: [270239ms] [EVSE] from 3 entering 4
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 49153 to 15118 (16bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 B2 00 12 8                                                                                                        0
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49153 (14bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 C0 00
Jun 28 08:28:55 rpievse python3.9[2137]: [270301ms] [EVSE] In state WaitForFlexibleRequest, received (21bytes) = 01 FE 80 01 00 00 00                                                                                                         0D 80 9A 02 00 40 80 C1 01 41 81 C2 10 B8
Jun 28 08:28:55 rpievse python3.9[2137]: [270314ms] [EVSE] {
Jun 28 08:28:55 rpievse python3.9[2137]: "msgName": "ContractAuthenticationReq",
Jun 28 08:28:55 rpievse python3.9[2137]: "info": "13 bytes to convert",
Jun 28 08:28:55 rpievse python3.9[2137]: "error": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "result": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:28:55 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "debug": "Line424"
Jun 28 08:28:55 rpievse python3.9[2137]: }
Jun 28 08:28:55 rpievse python3.9[2137]: [270329ms] [EVSE] responding (23bytes) = 01 FE 80 01 00 00 00 0F 80 9A 02 00 40 80 C1 01 41                                                                                                         81 C2 10 C0 00 00
Jun 28 08:28:55 rpievse python3.9[2137]: [270330ms] [EVSE] from 4 entering 4
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 49153 to 15118 (13bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 B8
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49153 (15bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 C0 00 00
Jun 28 08:28:55 rpievse python3.9[2137]: [SNIFFER] EXI from 49153 to 15118 (36bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 71 90 00 0                                                                                                        0 09 40 40 61 10 4E 04 07 0A 8C 30 10 20 50 C0 20 13 20 50 00
Jun 28 08:28:55 rpievse python3.9[2137]: [270446ms] [EVSE] In state WaitForFlexibleRequest, received (44bytes) = 01 FE 80 01 00 00 00                                                                                                         24 80 9A 02 00 40 80 C1 01 41 81 C2 10 71 90 00 00 09 40 40 61 10 4E 04 07 0A 8C 30 10 20 50 C0 20 13 20 50 00
Jun 28 08:28:55 rpievse python3.9[2137]: [270458ms] [EVSE] {
Jun 28 08:28:55 rpievse python3.9[2137]: "msgName": "ChargeParameterDiscoveryReq",
Jun 28 08:28:55 rpievse python3.9[2137]: "info": "36 bytes to convert",
Jun 28 08:28:55 rpievse python3.9[2137]: "error": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "result": "",
Jun 28 08:28:55 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:28:55 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVRequestedEnergyTransferType": "3",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVChargeParameter_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "DC_EVChargeParameter_isUsed": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "DC_EVStatus.EVRESSSOC": "74",
Jun 28 08:28:55 rpievse python3.9[2137]: "DC_EVStatus.EVReady": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVCabinConditioning_isUsed": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVRESSConditioning_isUsed": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVErrorCode": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "DC_EVErrorCodeText": "NO_ERROR",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumCurrentLimit.Value": "5000",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumCurrentLimit.Multiplier": "-1",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumCurrentLimit.Unit_isUsed": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumCurrentLimit.Unit": "3",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumPowerLimit_isUsed": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumPowerLimit.Value": "25000",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumPowerLimit.Multiplier": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumPowerLimit.Unit_isUsed": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumPowerLimit.Unit": "7",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumVoltageLimit.Value": "4160",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumVoltageLimit.Multiplier": "-1",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumVoltageLimit.Unit_isUsed": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVMaximumVoltageLimit.Unit": "5",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyCapacity_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyCapacity.Value": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyCapacity.Multiplier": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyCapacity.Unit_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyCapacity.Unit": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyRequest_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyRequest.Value": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyRequest.Multiplier": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyRequest.Unit_isUsed": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "EVEnergyRequest.Unit": "0",
Jun 28 08:28:55 rpievse python3.9[2137]: "FullSOC_isUsed": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "FullSOC": "100",
Jun 28 08:28:55 rpievse python3.9[2137]: "BulkSOC_isUsed": "1",
Jun 28 08:28:55 rpievse python3.9[2137]: "BulkSOC": "80",
Jun 28 08:28:55 rpievse python3.9[2137]: "debug": "Line9057Line9090Line9169Line9226Line9260Line400"
Jun 28 08:28:55 rpievse python3.9[2137]: }
Jun 28 08:28:55 rpievse python3.9[2137]: [270462ms] [EVSE] Received ChargeParameterDiscoveryReq. Extracting SoC parameters via DC
Jun 28 08:28:55 rpievse python3.9[2137]: Received SoC status from ChargeParameterDiscoveryReq.
Jun 28 08:28:55 rpievse python3.9[2137]:     Current SoC 74%
Jun 28 08:28:55 rpievse python3.9[2137]:     Full SoC 100%
Jun 28 08:28:55 rpievse python3.9[2137]:     Energy capacity 27000 Wh
Jun 28 08:28:55 rpievse python3.9[2137]:     Energy request -1 Wh
Jun 28 08:28:55 rpievse python3.9[2137]:     EVCCID ec65ccb685dc
Jun 28 08:28:56 rpievse python3.9[2137]: [270641ms] [EVSE] responding (49bytes) = 01 FE 80 01 00 00 00 29 80 9A 02 00 40 80 C1 01 41                                                                                                         81 C2 10 80 00 48 20 40 00 00 C9 90 02 06 20 50 19 30 80 C0 C8 02 06 4C 80 10 19 01 40 C8 0A 20
Jun 28 08:28:56 rpievse python3.9[2137]: [270642ms] [EVSE] from 4 entering 4
Jun 28 08:28:56 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49153 (41bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 80 00 48 2                                                                                                        0 40 00 00 C9 90 02 06 20 50 19 30 80 C0 C8 02 06 4C 80 10 19 01 40 C8 0A 20
Jun 28 08:28:56 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49153 (41bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 80 00 48 2                                                                                                        0 40 00 00 C9 90 02 06 20 50 19 30 80 C0 C8 02 06 4C 80 10 19 01 40 C8 0A 20
Jun 28 08:28:56 rpievse python3.9[2137]: [270920ms] connection closed
Jun 28 08:28:56 rpievse python3.9[2137]: [270921ms] [EVSE] re-initializing fsmEvse due to broken connection
Jun 28 08:28:56 rpievse python3.9[2137]: [270922ms] [EVSE] re-initializing fsmEvse
Jun 28 08:28:56 rpievse python3.9[2137]: [270923ms] Trying to reset the TCP socket
Jun 28 08:28:56 rpievse python3.9[2137]: [270924ms] pyPlcTcpSocket listening on port 15118
Jun 28 08:28:56 rpievse python3.9[2137]: [270926ms] The socket is linked the following IP addresses:
Jun 28 08:28:56 rpievse python3.9[2137]: [270927ms] ::1
Jun 28 08:28:56 rpievse python3.9[2137]: [270927ms] ::1
Jun 28 08:28:56 rpievse python3.9[2137]: [270928ms] ::1
Jun 28 08:28:56 rpievse python3.9[2137]: [271040ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:28:59 rpievse python3.9[2137]: [273719ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:29:01 rpievse python3.9[2137]: [276398ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5

Second try without unplugging the car, just switching SmartEVSE to off and then back to Smart to re-initiate the modem procedure.

Jun 28 08:34:55 rpievse python3.9[2137]: [629850ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:34:57 rpievse python3.9[2137]: [632525ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:00 rpievse python3.9[2137]: [635202ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:02 rpievse python3.9[2137]: [637393ms] received SLAC_PARAM.REQ
Jun 28 08:35:02 rpievse python3.9[2137]: [addressManager] pev has MAC EC:65:CC:B6:85:DC
Jun 28 08:35:02 rpievse python3.9[2137]: [637394ms] [EVSE] transmitting CM_SLAC_PARAM.CNF
Jun 28 08:35:03 rpievse python3.9[2137]: [637640ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637641ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637723ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637725ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637726ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637809ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637810ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637811ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637893ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:03 rpievse python3.9[2137]: [637895ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637896ms] received MNBC_SOUND.IND
Jun 28 08:35:03 rpievse python3.9[2137]: [637897ms] [EVSE] transmitting ATTEN_CHAR.IND
Jun 28 08:35:04 rpievse python3.9[2137]: [638790ms] received SLAC_MATCH.REQ
Jun 28 08:35:04 rpievse python3.9[2137]: [638791ms] [EVSE] transmitting SLAC_MATCH.CNF
Jun 28 08:35:06 rpievse python3.9[2137]: [640577ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:08 rpievse python3.9[2137]: [addressManager] pev has IP fe80:0000:0000:0000:ee65:ccff:feb6:85dc
Jun 28 08:35:08 rpievse python3.9[2137]: V2GTP (10bytes) = 01 FE 90 00 00 00 00 02 10 00
Jun 28 08:35:08 rpievse python3.9[2137]: Ok, this was a valid SDP request. We are the SECC. Sending SDP response.
Jun 28 08:35:08 rpievse python3.9[2137]: SDP payload (20bytes) = FE 80 00 00 00 00 00 00 DB 7C 36 04 B2 2B C7 CF 3B 0E 10 00
Jun 28 08:35:08 rpievse python3.9[2137]: V2Gframe (28bytes) = 01 FE 90 01 00 00 00 14 FE 80 00 00 00 00 00 00 DB 7C 36 04 B2 2B C7 CF 3B 0E 10 00
Jun 28 08:35:08 rpievse python3.9[2137]: [642854ms] Connection from ('fe80::ee65:ccff:feb6:85dc', 49154, 0, 4)
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 49154 to 15118 (34bytes) = 80 00 DB AB 93 71 D3 23 4B 71 D1 B9 81 89 91 89 D1 91 81 89 91 D2 6B 9B 3A 23 2B 30 02 00 00 00 00 40
Jun 28 08:35:08 rpievse python3.9[2137]: [642888ms] [EVSE] In state WaitForSupportedApplicationProtocolRequest, received (42bytes) = 01 FE 80 01 00 00 00 22 80 00 DB AB 93 71 D3 23 4B 71 D1 B9 81 89 91 89 D1 91 81 89 91 D2 6B 9B 3A 23 2B 30 02 00 00 00 00 40
Jun 28 08:35:08 rpievse python3.9[2137]: [642903ms] [EVSE] {
Jun 28 08:35:08 rpievse python3.9[2137]: "msgName": "supportedAppProtocolReq",
Jun 28 08:35:08 rpievse python3.9[2137]: "info": "34 bytes to convert",
Jun 28 08:35:08 rpievse python3.9[2137]: "error": "",
Jun 28 08:35:08 rpievse python3.9[2137]: "result": "Vehicle supports 1 protocols. ProtocolEntry#1 ProtocolNamespace=urn:din:70121:2012:MsgDef Version=2.0 SchemaID=0 Priority=1 ",
Jun 28 08:35:08 rpievse python3.9[2137]: "schema": "appHandshake",
Jun 28 08:35:08 rpievse python3.9[2137]: "AppProtocol_arrayLen": "1",
Jun 28 08:35:08 rpievse python3.9[2137]: "NameSpace_0": "urn:din:70121:2012:MsgDef",
Jun 28 08:35:08 rpievse python3.9[2137]: "Version_0": "2.0",
Jun 28 08:35:08 rpievse python3.9[2137]: "SchemaID_0": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "Priority_0": "1",
Jun 28 08:35:08 rpievse python3.9[2137]: "debug": ""
Jun 28 08:35:08 rpievse python3.9[2137]: }
Jun 28 08:35:08 rpievse python3.9[2137]: [642906ms] [EVSE] The car supports 1 schemas.
Jun 28 08:35:08 rpievse python3.9[2137]: [642907ms] [EVSE] The NameSpace urn:din:70121:2012:MsgDef has SchemaID 0
Jun 28 08:35:08 rpievse python3.9[2137]: [642908ms] [EVSE] Detected DIN
Jun 28 08:35:08 rpievse python3.9[2137]: [642920ms] [EVSE] responding (12bytes) = 01 FE 80 01 00 00 00 04 80 40 00 00
Jun 28 08:35:08 rpievse python3.9[2137]: [642921ms] [EVSE] from 0 entering 1
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49154 (4bytes) = 80 40 00 00
Jun 28 08:35:08 rpievse python3.9[2137]: [642979ms] [EVSE] In state WaitForSessionSetupRequest, received (22bytes) = 01 FE 80 01 00 00 00 0E 80 9A 00 40 11 D0 1B B1 97 32 DA 17 70 00
Jun 28 08:35:08 rpievse python3.9[2137]: [642990ms] [EVSE] {
Jun 28 08:35:08 rpievse python3.9[2137]: "msgName": "SessionSetupReq",
Jun 28 08:35:08 rpievse python3.9[2137]: "info": "14 bytes to convert",
Jun 28 08:35:08 rpievse python3.9[2137]: "error": "",
Jun 28 08:35:08 rpievse python3.9[2137]: "result": "",
Jun 28 08:35:08 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:35:08 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.SessionID": "00",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "EVCCID.bytesLen": "6",
Jun 28 08:35:08 rpievse python3.9[2137]: "EVCCID": "ec65ccb685dc",
Jun 28 08:35:08 rpievse python3.9[2137]: "debug": "Line532"
Jun 28 08:35:08 rpievse python3.9[2137]: }
Jun 28 08:35:08 rpievse python3.9[2137]: [643003ms] [EVSE] responding (31bytes) = 01 FE 80 01 00 00 00 17 80 9A 02 00 40 80 C1 01 41 81 C2 11 E0 20 1D 69 68 C0 C0 C0 C0 C0 80
Jun 28 08:35:08 rpievse python3.9[2137]: [643005ms] [EVSE] responding (31bytes) = 01 FE 80 01 00 00 00 17 80 9A 02 00 40 80 C1 01 41 81 C2 11 E0 20 1D 69 68 C0 C0 C0 C0 C0 80
Jun 28 08:35:08 rpievse python3.9[2137]: [643006ms] [EVSE] from 1 entering 2
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 49154 to 15118 (14bytes) = 80 9A 00 40 11 D0 1B B1 97 32 DA 17 70 00
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49154 (23bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 E0 20 1D 69 68 C0 C0 C0 C0 C0 80
Jun 28 08:35:08 rpievse python3.9[2137]: [643067ms] [EVSE] In state WaitForServiceDiscoveryRequest, received (22bytes) = 01 FE 80 01 00 00 00 0E 80 9A 02 00 40 80 C1 01 41 81 C2 11 94 00
Jun 28 08:35:08 rpievse python3.9[2137]: [643078ms] [EVSE] {
Jun 28 08:35:08 rpievse python3.9[2137]: "msgName": "ServiceDiscoveryReq",
Jun 28 08:35:08 rpievse python3.9[2137]: "info": "14 bytes to convert",
Jun 28 08:35:08 rpievse python3.9[2137]: "error": "",
Jun 28 08:35:08 rpievse python3.9[2137]: "result": "",
Jun 28 08:35:08 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:35:08 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "ServiceCategory_isUsed": "True",
Jun 28 08:35:08 rpievse python3.9[2137]: "debug": "Line508"
Jun 28 08:35:08 rpievse python3.9[2137]: }
Jun 28 08:35:08 rpievse python3.9[2137]: [643091ms] [EVSE] responding (27bytes) = 01 FE 80 01 00 00 00 13 80 9A 02 00 40 80 C1 01 41 81 C2 11 A0 01 20 02 41 00 C4
Jun 28 08:35:08 rpievse python3.9[2137]: [643093ms] [EVSE] from 2 entering 3
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 49154 to 15118 (14bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 94 00
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49154 (19bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 A0 01 20 02 41 00 C4
Jun 28 08:35:08 rpievse python3.9[2137]: [643157ms] [EVSE] In state WaitForServicePaymentSelectionRequest, received (24bytes) = 01 FE 80 01 00 00 00 10 80 9A 02 00 40 80 C1 01 41 81 C2 11 B2 00 12 80
Jun 28 08:35:08 rpievse python3.9[2137]: [643169ms] [EVSE] {
Jun 28 08:35:08 rpievse python3.9[2137]: "msgName": "ServicePaymentSelectionReq",
Jun 28 08:35:08 rpievse python3.9[2137]: "info": "16 bytes to convert",
Jun 28 08:35:08 rpievse python3.9[2137]: "error": "",
Jun 28 08:35:08 rpievse python3.9[2137]: "result": "",
Jun 28 08:35:08 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:35:08 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "SelectedPaymentOption": "ExternalPayment",
Jun 28 08:35:08 rpievse python3.9[2137]: "SelectedService.arrayLen": "1",
Jun 28 08:35:08 rpievse python3.9[2137]: "SelectedService.array": "0:1",
Jun 28 08:35:08 rpievse python3.9[2137]: "debug": "Line520"
Jun 28 08:35:08 rpievse python3.9[2137]: }
Jun 28 08:35:08 rpievse python3.9[2137]: [643184ms] [EVSE] responding (22bytes) = 01 FE 80 01 00 00 00 0E 80 9A 02 00 40 80 C1 01 41 81 C2 11 C0 00
Jun 28 08:35:08 rpievse python3.9[2137]: [643186ms] [EVSE] from 3 entering 4
Jun 28 08:35:08 rpievse python3.9[2137]: [643217ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 49154 to 15118 (16bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 B2 00 12 80
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49154 (14bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 C0 00
Jun 28 08:35:08 rpievse python3.9[2137]: [643247ms] [EVSE] In state WaitForFlexibleRequest, received (21bytes) = 01 FE 80 01 00 00 00 0D 80 9A 02 00 40 80 C1 01 41 81 C2 10 B8
Jun 28 08:35:08 rpievse python3.9[2137]: [643259ms] [EVSE] {
Jun 28 08:35:08 rpievse python3.9[2137]: "msgName": "ContractAuthenticationReq",
Jun 28 08:35:08 rpievse python3.9[2137]: "info": "13 bytes to convert",
Jun 28 08:35:08 rpievse python3.9[2137]: "error": "",
Jun 28 08:35:08 rpievse python3.9[2137]: "result": "",
Jun 28 08:35:08 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:35:08 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:35:08 rpievse python3.9[2137]: "debug": "Line424"
Jun 28 08:35:08 rpievse python3.9[2137]: }
Jun 28 08:35:08 rpievse python3.9[2137]: [643273ms] [EVSE] responding (23bytes) = 01 FE 80 01 00 00 00 0F 80 9A 02 00 40 80 C1 01 41 81 C2 10 C0 00 00
Jun 28 08:35:08 rpievse python3.9[2137]: [643275ms] [EVSE] from 4 entering 4
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 49154 to 15118 (13bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 B8
Jun 28 08:35:08 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49154 (15bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 C0 00 00
Jun 28 08:35:11 rpievse python3.9[2137]: [645907ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:14 rpievse python3.9[2137]: [648585ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:16 rpievse python3.9[2137]: [651263ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:16 rpievse python3.9[2137]: [651478ms] [EVSE] from 4 entering 0
Jun 28 08:35:19 rpievse python3.9[2137]: [653942ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:22 rpievse python3.9[2137]: [656619ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:24 rpievse python3.9[2137]: [659296ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:27 rpievse python3.9[2137]: [661972ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:30 rpievse python3.9[2137]: [664653ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:32 rpievse python3.9[2137]: [667329ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:35 rpievse python3.9[2137]: [670009ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:38 rpievse python3.9[2137]: [672686ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:40 rpievse python3.9[2137]: [675363ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:43 rpievse python3.9[2137]: [678039ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:46 rpievse python3.9[2137]: [680716ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:48 rpievse python3.9[2137]: [683394ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:51 rpievse python3.9[2137]: [686074ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:54 rpievse python3.9[2137]: [688752ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:56 rpievse python3.9[2137]: [691429ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:35:59 rpievse python3.9[2137]: [694105ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:36:02 rpievse python3.9[2137]: [696783ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:36:02 rpievse python3.9[2137]: [697479ms] connection closed
Jun 28 08:36:02 rpievse python3.9[2137]: [697480ms] [EVSE] re-initializing fsmEvse due to broken connection
Jun 28 08:36:02 rpievse python3.9[2137]: [697481ms] [EVSE] re-initializing fsmEvse
Jun 28 08:36:02 rpievse python3.9[2137]: [697482ms] Trying to reset the TCP socket
Jun 28 08:36:02 rpievse python3.9[2137]: [697483ms] pyPlcTcpSocket listening on port 15118
Jun 28 08:36:02 rpievse python3.9[2137]: [697485ms] The socket is linked the following IP addresses:
Jun 28 08:36:02 rpievse python3.9[2137]: [697486ms] ::1
Jun 28 08:36:02 rpievse python3.9[2137]: [697487ms] ::1
Jun 28 08:36:02 rpievse python3.9[2137]: [697487ms] ::1
Jun 28 08:36:04 rpievse python3.9[2137]: [699465ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:36:07 rpievse python3.9[2137]: [702141ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:36:10 rpievse python3.9[2137]: [704820ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5

Third try after unplugging the car and re-plugging it:

Jun 28 08:40:56 rpievse python3.9[2137]: [991411ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:40:59 rpievse python3.9[2137]: [994092ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:41:02 rpievse python3.9[2137]: [996772ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:41:03 rpievse python3.9[2137]: [998561ms] received SLAC_PARAM.REQ
Jun 28 08:41:03 rpievse python3.9[2137]: [addressManager] pev has MAC EC:65:CC:B6:85:DC
Jun 28 08:41:03 rpievse python3.9[2137]: [998562ms] [EVSE] transmitting CM_SLAC_PARAM.CNF
Jun 28 08:41:04 rpievse python3.9[2137]: [998808ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [998809ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [998891ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [998892ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [998893ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [998975ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [998976ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [998977ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [999060ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [999061ms] received MNBC_SOUND.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [999062ms] [EVSE] transmitting ATTEN_CHAR.IND
Jun 28 08:41:04 rpievse python3.9[2137]: [999469ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:41:05 rpievse python3.9[2137]: [999957ms] received SLAC_MATCH.REQ
Jun 28 08:41:05 rpievse python3.9[2137]: [999958ms] [EVSE] transmitting SLAC_MATCH.CNF
Jun 28 08:41:07 rpievse python3.9[2137]: [1002153ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:41:09 rpievse python3.9[2137]: [addressManager] pev has IP fe80:0000:0000:0000:ee65:ccff:feb6:85dc
Jun 28 08:41:09 rpievse python3.9[2137]: V2GTP (10bytes) = 01 FE 90 00 00 00 00 02 10 00
Jun 28 08:41:09 rpievse python3.9[2137]: Ok, this was a valid SDP request. We are the SECC. Sending SDP response.
Jun 28 08:41:09 rpievse python3.9[2137]: SDP payload (20bytes) = FE 80 00 00 00 00 00 00 DB 7C 36 04 B2 2B C7 CF 3B 0E 10 00
Jun 28 08:41:09 rpievse python3.9[2137]: V2Gframe (28bytes) = 01 FE 90 01 00 00 00 14 FE 80 00 00 00 00 00 00 DB 7C 36 04 B2 2B C7 CF 3B 0E 10 00
Jun 28 08:41:09 rpievse python3.9[2137]: [1004027ms] Connection from ('fe80::ee65:ccff:feb6:85dc', 49155, 0, 4)
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 49155 to 15118 (34bytes) = 80 00 DB AB 93 71 D3 23 4B 71 D1 B9 81 89 91 89 D1 91 81 89 91 D2 6B 9B 3A 23 2B 30 02 00 00 00 00 40
Jun 28 08:41:09 rpievse python3.9[2137]: [1004061ms] [EVSE] In state WaitForSupportedApplicationProtocolRequest, received (42bytes) = 01 FE 80 01 00 00 00 22 80 00 DB AB 93 71 D3 23 4B 71 D1 B9 81 89 91 89 D1 91 81 89 91 D2 6B 9B 3A 23 2B 30 02 00 00 00 00 40
Jun 28 08:41:09 rpievse python3.9[2137]: [1004076ms] [EVSE] {
Jun 28 08:41:09 rpievse python3.9[2137]: "msgName": "supportedAppProtocolReq",
Jun 28 08:41:09 rpievse python3.9[2137]: "info": "34 bytes to convert",
Jun 28 08:41:09 rpievse python3.9[2137]: "error": "",
Jun 28 08:41:09 rpievse python3.9[2137]: "result": "Vehicle supports 1 protocols. ProtocolEntry#1 ProtocolNamespace=urn:din:70121:2012:MsgDef Version=2.0 SchemaID=0 Priority=1 ",
Jun 28 08:41:09 rpievse python3.9[2137]: "schema": "appHandshake",
Jun 28 08:41:09 rpievse python3.9[2137]: "AppProtocol_arrayLen": "1",
Jun 28 08:41:09 rpievse python3.9[2137]: "NameSpace_0": "urn:din:70121:2012:MsgDef",
Jun 28 08:41:09 rpievse python3.9[2137]: "Version_0": "2.0",
Jun 28 08:41:09 rpievse python3.9[2137]: "SchemaID_0": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "Priority_0": "1",
Jun 28 08:41:09 rpievse python3.9[2137]: "debug": ""
Jun 28 08:41:09 rpievse python3.9[2137]: }
Jun 28 08:41:09 rpievse python3.9[2137]: [1004079ms] [EVSE] The car supports 1 schemas.
Jun 28 08:41:09 rpievse python3.9[2137]: [1004080ms] [EVSE] The NameSpace urn:din:70121:2012:MsgDef has SchemaID 0
Jun 28 08:41:09 rpievse python3.9[2137]: [1004080ms] [EVSE] Detected DIN
Jun 28 08:41:09 rpievse python3.9[2137]: [1004095ms] [EVSE] responding (12bytes) = 01 FE 80 01 00 00 00 04 80 40 00 00
Jun 28 08:41:09 rpievse python3.9[2137]: [1004096ms] [EVSE] from 0 entering 1
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49155 (4bytes) = 80 40 00 00
Jun 28 08:41:09 rpievse python3.9[2137]: [1004156ms] [EVSE] In state WaitForSessionSetupRequest, received (22bytes) = 01 FE 80 01 00 00 00 0E 80 9A 00 40 11 D0 1B B1 97 32 DA 17 70 00
Jun 28 08:41:09 rpievse python3.9[2137]: [1004168ms] [EVSE] {
Jun 28 08:41:09 rpievse python3.9[2137]: "msgName": "SessionSetupReq",
Jun 28 08:41:09 rpievse python3.9[2137]: "info": "14 bytes to convert",
Jun 28 08:41:09 rpievse python3.9[2137]: "error": "",
Jun 28 08:41:09 rpievse python3.9[2137]: "result": "",
Jun 28 08:41:09 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:41:09 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.SessionID": "00",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "EVCCID.bytesLen": "6",
Jun 28 08:41:09 rpievse python3.9[2137]: "EVCCID": "ec65ccb685dc",
Jun 28 08:41:09 rpievse python3.9[2137]: "debug": "Line532"
Jun 28 08:41:09 rpievse python3.9[2137]: }
Jun 28 08:41:09 rpievse python3.9[2137]: [1004180ms] [EVSE] responding (31bytes) = 01 FE 80 01 00 00 00 17 80 9A 02 00 40 80 C1 01 41 81 C2 11 E0 20 1D 69 68 C0 C0 C0 C0 C0 80
Jun 28 08:41:09 rpievse python3.9[2137]: [1004181ms] [EVSE] responding (31bytes) = 01 FE 80 01 00 00 00 17 80 9A 02 00 40 80 C1 01 41 81 C2 11 E0 20 1D 69 68 C0 C0 C0 C0 C0 80
Jun 28 08:41:09 rpievse python3.9[2137]: [1004182ms] [EVSE] from 1 entering 2
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 49155 to 15118 (14bytes) = 80 9A 00 40 11 D0 1B B1 97 32 DA 17 70 00
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49155 (23bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 E0 20 1D 69 68 C0 C0 C0 C0 C0 80
Jun 28 08:41:09 rpievse python3.9[2137]: [1004246ms] [EVSE] In state WaitForServiceDiscoveryRequest, received (22bytes) = 01 FE 80 01 00 00 00 0E 80 9A 02 00 40 80 C1 01 41 81 C2 11 94 00
Jun 28 08:41:09 rpievse python3.9[2137]: [1004257ms] [EVSE] {
Jun 28 08:41:09 rpievse python3.9[2137]: "msgName": "ServiceDiscoveryReq",
Jun 28 08:41:09 rpievse python3.9[2137]: "info": "14 bytes to convert",
Jun 28 08:41:09 rpievse python3.9[2137]: "error": "",
Jun 28 08:41:09 rpievse python3.9[2137]: "result": "",
Jun 28 08:41:09 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:41:09 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "ServiceCategory_isUsed": "True",
Jun 28 08:41:09 rpievse python3.9[2137]: "debug": "Line508"
Jun 28 08:41:09 rpievse python3.9[2137]: }
Jun 28 08:41:09 rpievse python3.9[2137]: [1004271ms] [EVSE] responding (27bytes) = 01 FE 80 01 00 00 00 13 80 9A 02 00 40 80 C1 01 41 81 C2 11 A0 01 20 02 41 00 C4
Jun 28 08:41:09 rpievse python3.9[2137]: [1004272ms] [EVSE] from 2 entering 3
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 49155 to 15118 (14bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 94 00
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49155 (19bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 A0 01 20 02 41 00 C4
Jun 28 08:41:09 rpievse python3.9[2137]: [1004337ms] [EVSE] In state WaitForServicePaymentSelectionRequest, received (24bytes) = 01 FE 80 01 00 00 00 10 80 9A 02 00 40 80 C1 01 41 81 C2 11 B2 00 12 80
Jun 28 08:41:09 rpievse python3.9[2137]: [1004349ms] [EVSE] {
Jun 28 08:41:09 rpievse python3.9[2137]: "msgName": "ServicePaymentSelectionReq",
Jun 28 08:41:09 rpievse python3.9[2137]: "info": "16 bytes to convert",
Jun 28 08:41:09 rpievse python3.9[2137]: "error": "",
Jun 28 08:41:09 rpievse python3.9[2137]: "result": "",
Jun 28 08:41:09 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:41:09 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "SelectedPaymentOption": "ExternalPayment",
Jun 28 08:41:09 rpievse python3.9[2137]: "SelectedService.arrayLen": "1",
Jun 28 08:41:09 rpievse python3.9[2137]: "SelectedService.array": "0:1",
Jun 28 08:41:09 rpievse python3.9[2137]: "debug": "Line520"
Jun 28 08:41:09 rpievse python3.9[2137]: }
Jun 28 08:41:09 rpievse python3.9[2137]: [1004364ms] [EVSE] responding (22bytes) = 01 FE 80 01 00 00 00 0E 80 9A 02 00 40 80 C1 01 41 81 C2 11 C0 00
Jun 28 08:41:09 rpievse python3.9[2137]: [1004365ms] [EVSE] from 3 entering 4
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 49155 to 15118 (16bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 B2 00 12 80
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49155 (14bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 11 C0 00
Jun 28 08:41:09 rpievse python3.9[2137]: [1004426ms] [EVSE] In state WaitForFlexibleRequest, received (21bytes) = 01 FE 80 01 00 00 00 0D 80 9A 02 00 40 80 C1 01 41 81 C2 10 B8
Jun 28 08:41:09 rpievse python3.9[2137]: [1004438ms] [EVSE] {
Jun 28 08:41:09 rpievse python3.9[2137]: "msgName": "ContractAuthenticationReq",
Jun 28 08:41:09 rpievse python3.9[2137]: "info": "13 bytes to convert",
Jun 28 08:41:09 rpievse python3.9[2137]: "error": "",
Jun 28 08:41:09 rpievse python3.9[2137]: "result": "",
Jun 28 08:41:09 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:41:09 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:41:09 rpievse python3.9[2137]: "debug": "Line424"
Jun 28 08:41:09 rpievse python3.9[2137]: }
Jun 28 08:41:09 rpievse python3.9[2137]: [1004452ms] [EVSE] responding (23bytes) = 01 FE 80 01 00 00 00 0F 80 9A 02 00 40 80 C1 01 41 81 C2 10 C0 00 00
Jun 28 08:41:09 rpievse python3.9[2137]: [1004454ms] [EVSE] from 4 entering 4
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 49155 to 15118 (13bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 B8
Jun 28 08:41:09 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49155 (15bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 C0 00 00
Jun 28 08:41:10 rpievse python3.9[2137]: [SNIFFER] EXI from 49155 to 15118 (36bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 71 90 00 00 09 60 40 61 10 4E 04 07 0A 8C 30 10 20 50 C0 20 13 20 50 00
Jun 28 08:41:10 rpievse python3.9[2137]: [1004571ms] [EVSE] In state WaitForFlexibleRequest, received (44bytes) = 01 FE 80 01 00 00 00 24 80 9A 02 00 40 80 C1 01 41 81 C2 10 71 90 00 00 09 60 40 61 10 4E 04 07 0A 8C 30 10 20 50 C0 20 13 20 50 00
Jun 28 08:41:10 rpievse python3.9[2137]: [1004585ms] [EVSE] {
Jun 28 08:41:10 rpievse python3.9[2137]: "msgName": "ChargeParameterDiscoveryReq",
Jun 28 08:41:10 rpievse python3.9[2137]: "info": "36 bytes to convert",
Jun 28 08:41:10 rpievse python3.9[2137]: "error": "",
Jun 28 08:41:10 rpievse python3.9[2137]: "result": "",
Jun 28 08:41:10 rpievse python3.9[2137]: "schema": "DIN",
Jun 28 08:41:10 rpievse python3.9[2137]: "g_errn": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "header.SessionID": "0102030405060708",
Jun 28 08:41:10 rpievse python3.9[2137]: "header.Notification_isUsed": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "header.Signature_isUsed": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVRequestedEnergyTransferType": "3",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVChargeParameter_isUsed": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "DC_EVChargeParameter_isUsed": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "DC_EVStatus.EVRESSSOC": "75",
Jun 28 08:41:10 rpievse python3.9[2137]: "DC_EVStatus.EVReady": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVCabinConditioning_isUsed": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVRESSConditioning_isUsed": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVErrorCode": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "DC_EVErrorCodeText": "NO_ERROR",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumCurrentLimit.Value": "5000",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumCurrentLimit.Multiplier": "-1",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumCurrentLimit.Unit_isUsed": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumCurrentLimit.Unit": "3",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumPowerLimit_isUsed": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumPowerLimit.Value": "25000",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumPowerLimit.Multiplier": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumPowerLimit.Unit_isUsed": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumPowerLimit.Unit": "7",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumVoltageLimit.Value": "4160",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumVoltageLimit.Multiplier": "-1",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumVoltageLimit.Unit_isUsed": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVMaximumVoltageLimit.Unit": "5",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyCapacity_isUsed": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyCapacity.Value": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyCapacity.Multiplier": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyCapacity.Unit_isUsed": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyCapacity.Unit": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyRequest_isUsed": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyRequest.Value": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyRequest.Multiplier": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyRequest.Unit_isUsed": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "EVEnergyRequest.Unit": "0",
Jun 28 08:41:10 rpievse python3.9[2137]: "FullSOC_isUsed": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "FullSOC": "100",
Jun 28 08:41:10 rpievse python3.9[2137]: "BulkSOC_isUsed": "1",
Jun 28 08:41:10 rpievse python3.9[2137]: "BulkSOC": "80",
Jun 28 08:41:10 rpievse python3.9[2137]: "debug": "Line9057Line9090Line9169Line9226Line9260Line400"
Jun 28 08:41:10 rpievse python3.9[2137]: }
Jun 28 08:41:10 rpievse python3.9[2137]: [1004588ms] [EVSE] Received ChargeParameterDiscoveryReq. Extracting SoC parameters via DC
Jun 28 08:41:10 rpievse python3.9[2137]: Received SoC status from ChargeParameterDiscoveryReq.
Jun 28 08:41:10 rpievse python3.9[2137]:     Current SoC 75%
Jun 28 08:41:10 rpievse python3.9[2137]:     Full SoC 100%
Jun 28 08:41:10 rpievse python3.9[2137]:     Energy capacity 27000 Wh
Jun 28 08:41:10 rpievse python3.9[2137]:     Energy request -1 Wh
Jun 28 08:41:10 rpievse python3.9[2137]:     EVCCID ec65ccb685dc
Jun 28 08:41:10 rpievse python3.9[2137]: [1004634ms] [EVSE] responding (49bytes) = 01 FE 80 01 00 00 00 29 80 9A 02 00 40 80 C1 01 41 81 C2 10 80 00 48 20 40 00 00 C9 90 02 06 20 50 19 30 80 C0 C8 02 06 4C 80 10 19 01 40 C8 0A 20
Jun 28 08:41:10 rpievse python3.9[2137]: [1004636ms] [EVSE] from 4 entering 4
Jun 28 08:41:10 rpievse python3.9[2137]: [SNIFFER] EXI from 15118 to 49155 (41bytes) = 80 9A 02 00 40 80 C1 01 41 81 C2 10 80 00 48 20 40 00 00 C9 90 02 06 20 50 19 30 80 C0 C8 02 06 4C 80 10 19 01 40 C8 0A 20
Jun 28 08:41:10 rpievse python3.9[2137]: [1004675ms] connection closed
Jun 28 08:41:10 rpievse python3.9[2137]: [1004676ms] [EVSE] re-initializing fsmEvse due to broken connection
Jun 28 08:41:10 rpievse python3.9[2137]: [1004676ms] [EVSE] re-initializing fsmEvse
Jun 28 08:41:10 rpievse python3.9[2137]: [1004677ms] Trying to reset the TCP socket
Jun 28 08:41:10 rpievse python3.9[2137]: [1004677ms] pyPlcTcpSocket listening on port 15118
Jun 28 08:41:10 rpievse python3.9[2137]: [1004679ms] The socket is linked the following IP addresses:
Jun 28 08:41:10 rpievse python3.9[2137]: [1004680ms] ::1
Jun 28 08:41:10 rpievse python3.9[2137]: [1004680ms] ::1
Jun 28 08:41:10 rpievse python3.9[2137]: [1004680ms] ::1
Jun 28 08:41:10 rpievse python3.9[2137]: [1004792ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:41:12 rpievse python3.9[2137]: [1007471ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5
Jun 28 08:41:15 rpievse python3.9[2137]: [1010154ms] [CONNMGR] 9 0 0 0 0 0 0  --> 5

@uhi22
Copy link
Owner Author

uhi22 commented Jun 28, 2023

Great, so I close this issue.
Regarding the EnergyCapacity: The car does not provide it, it says "EVEnergyCapacity_isUsed": "0". The remaining inconsistency of the pyPLC is, that it reports a 27kWh default value, which could be improved. The value is configured in pyPlc.ini, with soc_fallback_energy_capacity = 2700. Needs to be decided, whether this configurable value is good enough or a better strategy is necessary. May worth a new issue.

@uhi22 uhi22 closed this as completed Jun 28, 2023
@uhi22 uhi22 mentioned this issue Jul 14, 2023
Closed
@uhi22 uhi22 mentioned this issue Jan 19, 2024
Closed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@fluppie @uhi22