Enable OCPP 2.0.1 with AC ISO 15118-2, PnC and MaEVe

sahabulh commented 3 months ago

Two new MaEVe patches. One for enabling local MO root cert, the other for ignoring OCSP error.
Replaced the old MO leaf (CC) as the eMAID check digit wasn't correct
Added modified config yaml with CertificateInstallation disabled
Corresponding chages in the demo script to enable the above.

shankari commented 3 months ago

@activeshadow can you try to run this MRE to verify that it works properly. Feel free to clean it up as well. I can then pull and test one final time before merging.

shankari commented 3 months ago

We should also have a plan, as a follow-on, on engaging with the community for both MaEVe and EVerest so that they can be handled through configuration file changes and not require modifications to the code. I can certainly facilitate this, although I think @sahabulh should lead the effort since he did all the work 😄

shankari commented 3 months ago

also, @sahabulh which use cases does this work for? Does it work for AC 15118-2 EIM (the non-PnC dropdown) or only for PnC? It would be good if you would fill in the expected results, similar to https://github.com/EVerest/everest-demo/issues/35#issuecomment-2023837531 so that we can verify whether we get the same results while trying to reproduce

sahabulh commented 3 months ago

also, @sahabulh which use cases does this work for? Does it work for AC 15118-2 EIM (the non-PnC dropdown) or only for PnC? It would be good if you would fill in the expected results, similar to https://github.com/EVerest/everest-demo/issues/35#issuecomment-2023837531 so that we can verify whether we get the same results while trying to reproduce

Okay, I will post it when I get back home.

sahabulh commented 3 months ago

We should also have a plan, as a follow-on, on engaging with the community for both MaEVe and EVerest so that they can be handled through configuration file changes and not require modifications to the code. I can certainly facilitate this, although I think @sahabulh should lead the effort since he did all the work 😄

I don't know if OCSP can be turned off via configuration files. I couldn't find anything so I directly modified the code. But there might be other ways. I will create an issue and talk to the MaEVe devs.

There are two issues in this MRE. First, why does AC ISO 15118-2 drop-down option allow PnC? There is a separate option named AC ISO 15118-2 Plug&Charge. Second, it is charging now; the UI says "charging", the log says charging, but the power in the UI indicator stays at 0 kW. It doesn't go red and show 10+ kW. I will ask this in the same Zulip thread.

sahabulh commented 3 months ago

also, @sahabulh which use cases does this work for? Does it work for AC 15118-2 EIM (the non-PnC dropdown) or only for PnC? It would be good if you would fill in the expected results, similar to #35 (comment) so that we can verify whether we get the same results while trying to reproduce

AC 3ph 16A and AC 1ph 32A use EIM and work with the RFID swipe. AC ISO 15118-2 and AC ISO 15118-2 Plug&Charge work and behave similarly. Both use PnC. DC ISO 15118-2 gets stuck at the waiting for auth state. This is expected as we are using an AC config.

shankari commented 3 months ago

@sahabulh I meant with with logs like in my comment. You can use <details> to make the logs expandable (like I did in those comments)

sahabulh commented 3 months ago

You can use <details> to make the logs expandable

Yeah, I know. I have done that before in the issues. Here you are:

AC 3ph 16A - Charges

``` 2024-03-31 06:36:34.134972 [INFO] car_simulator_1 :: { cmd: 'sleep', args: [ 1 ], exec: [Function (anonymous)] } 2024-03-31 06:36:35.497419 [INFO] car_simulator_1 :: { cmd: 'iec_wait_pwr_ready', args: [], exec: [Function (anonymous)] } 2024-03-31 06:36:35.861329 [INFO] evse_manager_1: :: SYS Session logging started. 2024-03-31 06:36:35.861482 [INFO] evse_manager_1: :: EVSE IEC Session Started: EVConnected 2024-03-31 06:36:37.083103 [INFO] token_provider_ :: Publishing new dummy token: {"id_token":{"value":"DEADBEEF","type":"ISO14443"},"authorization_type":"RFID","prevalidated":false,"connectors":[1]} 2024-03-31 06:36:37.083652 [INFO] auth:Auth :: Received new token: { "authorization_type": "RFID", "connectors": [ 1 ], "id_token": { "type": "ISO14443", "value": "DEADBEEF" }, "prevalidated": false } 2024-03-31 06:36:37.084961 [INFO] ocpp:OCPP201 :: Found invalid entry in AuthCache: Sending new request 2024-03-31 06:36:37.129067 [INFO] auth:Auth :: Providing authorization to connector#1 2024-03-31 06:36:37.216877 [INFO] auth:Auth :: Result for token: DEADBEEF: ACCEPTED 2024-03-31 06:36:37.263651 [INFO] evse_manager_1: :: EVSE IEC EIM Authorization received 2024-03-31 06:36:37.263893 [INFO] evse_manager_1: :: EVSE IEC Transaction Started (0 kWh) 2024-03-31 06:36:37.264231 [INFO] evse_manager_1: :: EVSE IEC AC mode, HLC enabled(X1), matching already started. We are in X1 so we can go directly to nominal PWM. 2024-03-31 06:36:37.264303 [INFO] evse_manager_1: :: EVSE IEC Charger state: Wait for Auth->PrepareCharging 2024-03-31 06:36:37.264477 [INFO] evse_manager_1: :: EVSE IEC Set PWM On (53.33333611488342%) took 0 ms 2024-03-31 06:36:37.449735 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:36:37.454582 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:36:37.489045 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:36:37.499012 [INFO] car_simulator_1 :: { cmd: 'sleep', args: [ 1 ], exec: [Function (anonymous)] } 2024-03-31 06:36:37.574403 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:36:38.750157 [INFO] car_simulator_1 :: { cmd: 'draw_power_regulated', args: [ 16, 3 ], exec: [Function (anonymous)] } 2024-03-31 06:36:38.750383 [INFO] car_simulator_1 :: { cmd: 'sleep', args: [ 36000 ], exec: [Function (anonymous)] } 2024-03-31 06:36:38.905593 [INFO] evse_manager_1: :: CAR IEC Event CarRequestedPower 2024-03-31 06:36:38.905882 [INFO] evse_manager_1: :: EVSE IEC Charger state: PrepareCharging->Charging 2024-03-31 06:36:39.157754 [INFO] evse_manager_1: :: EVSE IEC Event PowerOn 2024-03-31 06:36:42.626665 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:36:42.636679 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:36:42.637436 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:36:42.725417 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:36:47.243473 [INFO] car_simulator_1 :: { cmd: 'unplug', args: [], exec: [Function (anonymous)] } 2024-03-31 06:36:47.456895 [INFO] evse_manager_1: :: EVSE IEC Event PowerOff 2024-03-31 06:36:47.676778 [INFO] evse_manager_1: :: EVSE ISO SLAC UNMATCHED 2024-03-31 06:36:47.677054 [INFO] evse_manager_1: :: EVSE ISO D-LINK_READY (false) 2024-03-31 06:36:47.720893 [INFO] evse_manager_1: :: CAR IEC Event CarUnplugged 2024-03-31 06:36:47.721021 [INFO] evse_manager_1: :: EVSE IEC Charger state: Charging->StoppingCharging 2024-03-31 06:36:47.721228 [INFO] evse_manager_1: :: EVSE IEC Set PWM Off 2024-03-31 06:36:47.722500 [INFO] evse_manager_1: :: EVSE IEC Charger state: StoppingCharging->Finished 2024-03-31 06:36:47.722786 [INFO] evse_manager_1: :: EVSE IEC Transaction Finished: EVDisconnected (0 kWh) 2024-03-31 06:36:47.769690 [INFO] evse_manager_1: :: EVSE IEC Session Finished 2024-03-31 06:36:47.769826 [INFO] evse_manager_1: :: SYS Session logging stopped. ```

AC ISO 15118-2 - Charges

``` 2024-03-31 06:40:14.449279 [INFO] car_simulator_1 :: { cmd: 'sleep', args: [ 1 ], exec: [Function (anonymous)] } 2024-03-31 06:40:15.699934 [INFO] car_simulator_1 :: { cmd: 'iso_wait_slac_matched', args: [], exec: [Function (anonymous)] } 2024-03-31 06:40:15.978934 [INFO] evse_manager_1: :: SYS Session logging started. 2024-03-31 06:40:15.979094 [INFO] evse_manager_1: :: EVSE IEC Session Started: EVConnected 2024-03-31 06:40:18.306095 [INFO] evse_manager_1: :: EVSE ISO SLAC MATCHED 2024-03-31 06:40:18.306868 [INFO] evse_manager_1: :: EVSE ISO D-LINK_READY (true) 2024-03-31 06:40:18.454048 [INFO] car_simulator_1 :: { cmd: 'iso_start_v2g_session', args: [ 'externalpayment', 'ac_three_phase_core' ], exec: [Function (anonymous)] } 2024-03-31 06:40:18.501338 [INFO] car_simulator_1 :: { cmd: 'iso_wait_pwr_ready', args: [], exec: [Function (anonymous)] } 2024-03-31 06:40:19.283207 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:40:19.287994 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:40:19.321096 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:40:19.476613 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:40:19.978377 [INFO] iso15118_charge :: Received packet from [fe80::42:acff:fe12:4]:56402 with security 0x00 and protocol 0x00 2024-03-31 06:40:19.978437 [INFO] iso15118_charge :: SDP requested TLS, announcing TLS 2024-03-31 06:40:19.978487 [INFO] iso15118_charge :: sendto([fe80::42:acff:fe12:4]:56402) succeeded 2024-03-31 06:40:19.980881 [INFO] iso15118_charge :: Incoming connection on eth0 from [a00:95ec:0:0:fe80::]:38380 2024-03-31 06:40:19.980965 [INFO] iso15118_charge :: Started new TLS connection thread 2024-03-31 06:40:19.982557 [INFO] evse_security:E :: Requesting certificate file: [V2G] file:"/workspace/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem" 2024-03-31 06:40:20.070855 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:40:20.113146 [INFO] iso15118_charge :: Performing TLS handshake 2024-03-31 06:40:20.119298 [INFO] iso15118_charge :: Using V2G-root of issuer V2GRootCA (SHA-1: 0x212b3816249642e4f5b855c7f1d14fc0fa4dc3b2) for TLS-handshake 2024-03-31 06:40:20.122850 [INFO] iso15118_charge :: TLS handshake succeeded 2024-03-31 06:40:20.191126 [INFO] iso15118_charge :: Handling SupportedAppProtocolReq 2024-03-31 06:40:20.191449 [INFO] iso15118_charge :: Protocol negotiation was successful. Selected protocol is ISO15118 2024-03-31 06:40:20.192042 [INFO] evse_manager_1: :: CAR ISO V2G SupportedAppProtocolReq 2024-03-31 06:40:20.276611 [INFO] evse_manager_1: :: EVSE ISO V2G SupportedAppProtocolRes 2024-03-31 06:40:21.195453 [INFO] iso15118_charge :: SessionSetupReq.EVCCID: 02:42:AC:12:00:04 2024-03-31 06:40:21.195500 [INFO] iso15118_charge :: No session_id found or not equal to the id from the preceding v2g session. Generating random session id. 2024-03-31 06:40:21.195527 [INFO] iso15118_charge :: Created new session with id 0x3458279622328634441 2024-03-31 06:40:21.196159 [INFO] evse_manager_1: :: CAR ISO V2G SessionSetupReq 2024-03-31 06:40:21.304859 [INFO] evse_manager_1: :: EVSE ISO V2G SessionSetupRes 2024-03-31 06:40:21.996839 [INFO] evse_manager_1: :: CAR ISO V2G ServiceDiscoveryReq 2024-03-31 06:40:22.088259 [INFO] evse_manager_1: :: EVSE ISO V2G ServiceDiscoveryRes 2024-03-31 06:40:22.697914 [INFO] iso15118_charge :: SelectedPaymentOption: Contract 2024-03-31 06:40:22.700231 [INFO] evse_manager_1: :: CAR ISO V2G PaymentServiceSelectionReq 2024-03-31 06:40:22.797116 [INFO] evse_manager_1: :: EVSE ISO V2G PaymentServiceSelectionRes 2024-03-31 06:40:23.505836 [INFO] auth:Auth :: Received new token: { "authorization_type": "PlugAndCharge", "certificate": "-----BEGIN CERTIFICATE-----\nMIICRTCCAeqgAwIBAgICMEQwCgYIKoZIzj0EAwIwVzEiMCAGA1UEAwwZUEtJLUV4dF9DUlRfTU9fU1VCMl9WQUxJRDEQMA4GA1UECgwHRVZlcmVzdDELMAkGA1UEBhMCREUxEjAQBgoJkiaJk/IsZAEZFgJNTzAeFw0yNDAzMjkyMTUxMDJaFw0yNjAzMjkyMTUxMDJaME0xGDAWBgNVBAMMD1VLU1dJMTIzNDU2Nzg5RzEQMA4GA1UECgwHRVZlcmVzdDELMAkGA1UEBhMCREUxEjAQBgoJkiaJk/IsZAEZFgJNTzBZMBMGByqGSM49AgEGCCqGSM49AwEHA0IABJEjYSg4WB9qJpE5eCuYNj/mcsdsHeLe7ySlyxr+jiZAzeqwrNSRikQ5Eqx5qJ0SjKjlFZRpOSCJlkR4ZcAsQ6+jga8wgawwDAYDVR0TAQH/BAIwADAOBgNVHQ8BAf8EBAMCA+gwHQYDVR0OBBYEFEOuT6L+OlKn0YSukXQLCHBwLkuXMG0GCCsGAQUFBwEBBGEwXzAkBggrBgEFBQcwAYYYaHR0cHM6Ly93d3cuZXhhbXBsZS5jb20vMDcGCCsGAQUFBzAChitodHRwczovL3d3dy5leGFtcGxlLmNvbS9JbnRlcm1lZGlhdGUtQ0EuY2VyMAoGCCqGSM49BAMCA0kAMEYCIQCORTo+LrIHkmEAtnAf7Gpmlzif5nQbvGruS+Nijh59ewIhAO/jH6dwWdbUyBEnPvCop63Bcn7spYiQubwi4ei2cnWY\n-----END CERTIFICATE-----\n-----BEGIN CERTIFICATE-----\nMIICVTCCAfqgAwIBAgICMEMwCgYIKoZIzj0EAwIwVzEiMCAGA1UEAwwZUEtJLUV4dF9DUlRfTU9fU1VCMV9WQUxJRDEQMA4GA1UECgwHRVZlcmVzdDELMAkGA1UEBhMCREUxEjAQBgoJkiaJk/IsZAEZFgJNTzAeFw0yNDAzMjkyMTUxMDJaFw0yODAzMjgyMTUxMDJaMFcxIjAgBgNVBAMMGVBLSS1FeHRfQ1JUX01PX1NVQjJfVkFMSUQxEDAOBgNVBAoMB0VWZXJlc3QxCzAJBgNVBAYTAkRFMRIwEAYKCZImiZPyLGQBGRYCTU8wWTATBgcqhkjOPQIBBggqhkjOPQMBBwNCAASJZtlPa8eWnBD8Ekerx5kzHeb/0BaCYpLY2ably2tbuKoO/6hg0AvR5qaMmLnR6ZhWVsnXSO6Q+t48Aj1CeEdxo4G1MIGyMBIGA1UdEwEB/wQIMAYBAf8CAQAwDgYDVR0PAQH/BAQDAgHGMB0GA1UdDgQWBBS4s1DC/ki+wVtZKCOewNu6c0NY+TBtBggrBgEFBQcBAQRhMF8wJAYIKwYBBQUHMAGGGGh0dHBzOi8vd3d3LmV4YW1wbGUuY29tLzA3BggrBgEFBQcwAoYraHR0cHM6Ly93d3cuZXhhbXBsZS5jb20vSW50ZXJtZWRpYXRlLUNBLmNlcjAKBggqhkjOPQQDAgNJADBGAiEA3D38x+8nRs+zcpbi4I68pnYeSE2AWGoS1dsKJaFYqPECIQC4dLx7Lnm4Ozn6kEKO7XfjdioH/e8jvbYYcp4SRsQQrQ==\n-----END CERTIFICATE-----\n-----BEGIN CERTIFICATE-----\nMIICQjCCAemgAwIBAgICMEIwCgYIKoZIzj0EAwIwRjERMA8GA1UEAwwITU9Sb290Q0ExEDAOBgNVBAoMB0VWZXJlc3QxCzAJBgNVBAYTAkRFMRIwEAYKCZImiZPyLGQBGRYCTU8wHhcNMjQwMzI5MjE1MTAyWhcNMjgwMzI4MjE1MTAyWjBXMSIwIAYDVQQDDBlQS0ktRXh0X0NSVF9NT19TVUIxX1ZBTElEMRAwDgYDVQQKDAdFVmVyZXN0MQswCQYDVQQGEwJERTESMBAGCgmSJomT8ixkARkWAk1PMFkwEwYHKoZIzj0CAQYIKoZIzj0DAQcDQgAEZX75ORQ9ZOlA9/63r/wmXYldnSAdhVtKwKE/JLwBQMDpJKz0cAo5u6PfFYMnleGngvm9B4JS4F3ME6qXQLG1XqOBtTCBsjASBgNVHRMBAf8ECDAGAQH/AgEBMA4GA1UdDwEB/wQEAwIBBjAdBgNVHQ4EFgQUoFxi/Y8KcsAh/PniGtZMiyoVMMMwbQYIKwYBBQUHAQEEYTBfMCQGCCsGAQUFBzABhhhodHRwczovL3d3dy5leGFtcGxlLmNvbS8wNwYIKwYBBQUHMAKGK2h0dHBzOi8vd3d3LmV4YW1wbGUuY29tL0ludGVybWVkaWF0ZS1DQS5jZXIwCgYIKoZIzj0EAwIDRwAwRAIgVA5ki7sFnQ9JZjrMd2JLYo0D0eJhESKef8rL3WUSp30CIAJmLyWjQATx8KN+oooxQWuubUkvsp2Nwjh6Gs/UJjoM\n-----END CERTIFICATE-----\n", "connectors": [ 1 ], "id_token": { "type": "eMAID", "value": "UKSWI123456789G" } } 2024-03-31 06:40:23.506098 [INFO] evse_manager_1: :: CAR ISO V2G PaymentDetailsReq 2024-03-31 06:40:23.512113 [INFO] evse_security:E :: Building new certificate hierarchy! 2024-03-31 06:40:23.526427 [INFO] ocpp:OCPP201 :: Local contract validation result: Valid 2024-03-31 06:40:23.533476 [INFO] ocpp:OCPP201 :: Online: Pass generated OCSP data to CSMS 2024-03-31 06:40:23.601409 [INFO] evse_manager_1: :: EVSE ISO V2G PaymentDetailsRes 2024-03-31 06:40:23.621925 [INFO] ocpp:OCPP201 :: CSMS idToken status: Accepted 2024-03-31 06:40:23.621982 [INFO] ocpp:OCPP201 :: CSMS certificate status: Accepted 2024-03-31 06:40:23.623023 [INFO] auth:Auth :: Providing authorization to connector#1 2024-03-31 06:40:23.681748 [INFO] evse_manager_1: :: EVSE IEC Transaction Started (0 kWh) 2024-03-31 06:40:23.682595 [INFO] evse_manager_1: :: EVSE IEC PnC Authorization received 2024-03-31 06:40:23.682717 [INFO] evse_manager_1: :: EVSE IEC AC mode, HLC enabled, PnC auth received. We will continue with 5percent independent on how we started. 2024-03-31 06:40:23.682832 [INFO] evse_manager_1: :: EVSE IEC Charger state: Wait for Auth->PrepareCharging 2024-03-31 06:40:23.683154 [INFO] evse_manager_1: :: EVSE IEC Set PWM On (5.000000074505806%) took 0 ms 2024-03-31 06:40:23.782830 [INFO] auth:Auth :: Result for token: UKSWI123456789G: ACCEPTED 2024-03-31 06:40:24.479516 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:40:24.484285 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:40:24.511493 [INFO] evse_manager_1: :: CAR ISO V2G AuthorizationReq 2024-03-31 06:40:24.520951 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:40:24.603041 [INFO] evse_manager_1: :: EVSE ISO V2G AuthorizationRes 2024-03-31 06:40:24.606303 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:40:25.146611 [INFO] iso15118_charge :: Parameter-phase started 2024-03-31 06:40:25.149462 [INFO] iso15118_charge :: Selected energy transfer mode: AC_three_phase_core 2024-03-31 06:40:25.197475 [INFO] evse_manager_1: :: CAR ISO V2G ChargeParameterDiscoveryReq 2024-03-31 06:40:25.244197 [INFO] evse_manager_1: :: EVSE ISO V2G ChargeParameterDiscoveryRes 2024-03-31 06:40:25.458043 [INFO] car_simulator_1 :: { cmd: 'iso_draw_power_regulated', args: [ 16, 3 ], exec: [Function (anonymous)] } 2024-03-31 06:40:25.458335 [INFO] car_simulator_1 :: { cmd: 'sleep', args: [ 36000 ], exec: [Function (anonymous)] } 2024-03-31 06:40:25.652311 [INFO] evse_manager_1: :: CAR IEC Event CarRequestedPower 2024-03-31 06:40:25.790742 [INFO] iso15118_charge :: Waiting for contactor is closed 2024-03-31 06:40:25.792549 [INFO] evse_manager_1: :: CAR ISO AC HLC Close contactor 2024-03-31 06:40:25.879298 [INFO] evse_manager_1: :: EVSE IEC Charger state: PrepareCharging->Charging 2024-03-31 06:40:25.928950 [INFO] evse_manager_1: :: EVSE IEC Event PowerOn 2024-03-31 06:40:25.977275 [WARN] iso15118_charge void dlog_func(dloglevel_t, const char*, int, const char*, const char*, ...) :: Response message (type 14) not configured within 98 ms (took 187 ms) 2024-03-31 06:40:25.977670 [INFO] evse_manager_1: :: CAR ISO V2G PowerDeliveryReq 2024-03-31 06:40:25.977984 [INFO] evse_manager_1: :: EVSE ISO V2G PowerDeliveryRes 2024-03-31 06:40:26.518189 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:26.616220 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:27.182130 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:27.265028 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:27.794011 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:27.892874 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:28.435693 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:28.531778 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:29.096197 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:29.192425 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:29.660972 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:40:29.675082 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:40:29.693235 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:40:29.728839 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:29.780000 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:40:29.826971 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:30.364610 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:30.472989 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:31.004777 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:31.099153 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:31.653998 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:31.731532 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:32.280663 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:32.376500 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:32.911275 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:33.009919 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:33.555807 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:33.651876 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:34.200699 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:34.328972 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:34.783044 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:40:34.787740 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:40:34.824971 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:40:34.908947 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:34.910621 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:40:34.978443 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:35.549749 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:35.648962 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:36.194218 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:36.328918 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:36.831484 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:36.952974 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:37.457620 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:37.557420 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:38.101959 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:38.242505 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:38.553095 [INFO] car_simulator_1 :: { cmd: 'iso_stop_charging', args: [], exec: [Function (anonymous)] } 2024-03-31 06:40:38.728945 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:40:38.805229 [INFO] car_simulator_1 :: { cmd: 'iso_wait_v2g_session_stopped', args: [], exec: [Function (anonymous)] } 2024-03-31 06:40:38.827155 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:40:38.912286 [INFO] evse_manager_1: :: EVSE IEC Event PowerOff 2024-03-31 06:40:38.913613 [INFO] evse_manager_1: :: CAR IEC Event CarRequestedStopPower 2024-03-31 06:40:38.913745 [INFO] evse_manager_1: :: EVSE IEC Charger state: Charging->Car Paused 2024-03-31 06:40:39.364189 [INFO] evse_manager_1: :: CAR ISO AC HLC Open contactor 2024-03-31 06:40:39.364451 [INFO] evse_manager_1: :: CAR ISO V2G PowerDeliveryReq 2024-03-31 06:40:39.462749 [INFO] evse_manager_1: :: EVSE ISO V2G PowerDeliveryRes 2024-03-31 06:40:39.960470 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:40:39.966019 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:40:39.966652 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:40:40.008878 [INFO] evse_manager_1: :: CAR ISO V2G SessionStopReq 2024-03-31 06:40:40.054309 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:40:40.088226 [INFO] iso15118_charge :: v2g_dispatch_connection exited with 0 2024-03-31 06:40:40.088270 [INFO] iso15118_charge :: Closing TLS connection 2024-03-31 06:40:40.088572 [INFO] evse_manager_1: :: EVSE ISO V2G SessionStopRes 2024-03-31 06:40:42.088402 [INFO] iso15118_charge :: TLS connection closed gracefully 2024-03-31 06:40:42.088462 [INFO] iso15118_charge :: Closing TLS connection thread 2024-03-31 06:40:42.089426 [INFO] evse_manager_1: :: EVSE ISO D-LINK_TERMINATE.req 2024-03-31 06:40:42.089559 [INFO] evse_manager_1: :: EVSE IEC Set PWM Off 2024-03-31 06:40:42.136902 [INFO] evse_manager_1: :: EVSE IEC Charger state: Car Paused->StoppingCharging 2024-03-31 06:40:42.184792 [INFO] evse_manager_1: :: EVSE ISO SLAC UNMATCHED 2024-03-31 06:40:42.184967 [INFO] evse_manager_1: :: EVSE ISO D-LINK_READY (false) 2024-03-31 06:40:45.099688 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:40:45.106232 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:40:45.140941 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:40:45.222544 [INFO] car_simulator_1 :: { cmd: 'unplug', args: [], exec: [Function (anonymous)] } 2024-03-31 06:40:45.227274 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:40:45.780941 [INFO] evse_manager_1: :: CAR IEC Event CarUnplugged 2024-03-31 06:40:45.781110 [INFO] evse_manager_1: :: EVSE IEC Charger state: StoppingCharging->Finished 2024-03-31 06:40:45.781370 [INFO] evse_manager_1: :: EVSE IEC Transaction Finished: EVDisconnected (0 kWh) 2024-03-31 06:40:45.869083 [INFO] evse_manager_1: :: EVSE IEC Session Finished 2024-03-31 06:40:45.869201 [INFO] evse_manager_1: :: SYS Session logging stopped. ```

DC ISO 15118-2 - Stuck at "waiting for auth" phase (expected)

``` 2024-03-31 06:42:17.297250 [INFO] car_simulator_1 :: { cmd: 'sleep', args: [ 1 ], exec: [Function (anonymous)] } 2024-03-31 06:42:17.722833 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:42:17.727576 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:42:17.728202 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:42:17.813329 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:42:18.548776 [INFO] car_simulator_1 :: { cmd: 'iso_wait_slac_matched', args: [], exec: [Function (anonymous)] } 2024-03-31 06:42:18.548901 [INFO] car_simulator_1 :: { cmd: 'iso_start_v2g_session', args: [ 'externalpayment', 'dc_extended' ], exec: [Function (anonymous)] } 2024-03-31 06:42:18.593205 [INFO] car_simulator_1 :: { cmd: 'iso_wait_pwr_ready', args: [], exec: [Function (anonymous)] } 2024-03-31 06:42:18.977349 [INFO] evse_manager_1: :: SYS Session logging started. 2024-03-31 06:42:18.977490 [INFO] evse_manager_1: :: EVSE IEC Session Started: EVConnected 2024-03-31 06:42:19.990369 [INFO] iso15118_charge :: Received packet from [fe80::42:acff:fe12:4]:44165 with security 0x00 and protocol 0x00 2024-03-31 06:42:19.990423 [INFO] iso15118_charge :: SDP requested TLS, announcing TLS 2024-03-31 06:42:19.990448 [INFO] iso15118_charge :: sendto([fe80::42:acff:fe12:4]:44165) succeeded 2024-03-31 06:42:19.991802 [INFO] iso15118_charge :: Incoming connection on eth0 from [a00:b874:0:0:fe80::]:47220 2024-03-31 06:42:19.991919 [INFO] iso15118_charge :: Started new TLS connection thread 2024-03-31 06:42:20.033409 [INFO] evse_security:E :: Requesting certificate file: [V2G] file:"/workspace/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem" 2024-03-31 06:42:20.079760 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:42:20.121168 [INFO] iso15118_charge :: Performing TLS handshake 2024-03-31 06:42:20.127345 [INFO] iso15118_charge :: Using V2G-root of issuer V2GRootCA (SHA-1: 0x212b3816249642e4f5b855c7f1d14fc0fa4dc3b2) for TLS-handshake 2024-03-31 06:42:20.130943 [INFO] iso15118_charge :: TLS handshake succeeded 2024-03-31 06:42:20.195880 [INFO] iso15118_charge :: Handling SupportedAppProtocolReq 2024-03-31 06:42:20.196275 [INFO] iso15118_charge :: Protocol negotiation was successful. Selected protocol is ISO15118 2024-03-31 06:42:20.205058 [INFO] evse_manager_1: :: CAR ISO V2G SupportedAppProtocolReq 2024-03-31 06:42:20.205398 [INFO] evse_manager_1: :: EVSE ISO V2G SupportedAppProtocolRes 2024-03-31 06:42:20.919777 [INFO] iso15118_charge :: SessionSetupReq.EVCCID: 02:42:AC:12:00:04 2024-03-31 06:42:20.919956 [INFO] iso15118_charge :: No session_id found or not equal to the id from the preceding v2g session. Generating random session id. 2024-03-31 06:42:20.920036 [INFO] iso15118_charge :: Created new session with id 0x8232156218064370733 2024-03-31 06:42:20.965211 [INFO] evse_manager_1: :: CAR ISO V2G SessionSetupReq 2024-03-31 06:42:21.016285 [INFO] evse_manager_1: :: EVSE ISO V2G SessionSetupRes 2024-03-31 06:42:21.680893 [INFO] evse_manager_1: :: CAR ISO V2G ServiceDiscoveryReq 2024-03-31 06:42:21.778671 [INFO] evse_manager_1: :: EVSE ISO V2G ServiceDiscoveryRes 2024-03-31 06:42:22.858780 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:42:22.863490 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:42:22.901175 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:42:22.988822 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:42:26.884998 [ERRO] iso15118_charge void dlog_func(dloglevel_t, const char*, int, const char*, const char*, ...) :: Timeout waiting for next request or peer closed connection 2024-03-31 06:42:26.885275 [INFO] iso15118_charge :: v2g_dispatch_connection exited with -1 2024-03-31 06:42:26.885327 [INFO] iso15118_charge :: Closing TLS connection 2024-03-31 06:42:27.991784 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:42:27.996524 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:42:28.032955 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:42:28.118262 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:42:28.885658 [INFO] iso15118_charge :: TLS connection closed gracefully 2024-03-31 06:42:28.885821 [INFO] iso15118_charge :: Closing TLS connection thread 2024-03-31 06:42:28.888364 [ERRO] iso15118_car pybind11_init_everestpy(pybind11::module_&):: :: EVCC tried to initiate a V2GCommunicationSession, but maximum number of SDP retry cycles (1) is now reached. Shutting down high-level communication. Unplug and plug in the cable again if you want to start anew. Traceback (most recent call last): File "/workspace/dist/libexec/everest/modules/PyEvJosev/../../3rd_party/josev/iso15118/evcc/comm_session_handler.py", line 567, in get_from_rcv_queue await self.restart_sdp(True) File "/workspace/dist/libexec/everest/modules/PyEvJosev/../../3rd_party/josev/iso15118/evcc/comm_session_handler.py", line 384, in restart_sdp raise SDPFailedError( iso15118.shared.exceptions.SDPFailedError: EVCC tried to initiate a V2GCommunicationSession, but maximum number of SDP retry cycles (1) is now reached. Shutting down high-level communication. Unplug and plug in the cable again if you want to start anew. 2024-03-31 06:42:33.162846 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:42:33.168113 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:42:33.205065 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:42:33.292819 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:42:38.301956 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:42:38.310796 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:42:38.338202 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:42:38.426185 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:42:40.149166 [INFO] car_simulator_1 :: { cmd: 'iso_stop_charging', args: [], exec: [Function (anonymous)] } 2024-03-31 06:42:40.397133 [INFO] car_simulator_1 :: { cmd: 'iso_wait_v2g_session_stopped', args: [], exec: [Function (anonymous)] } 2024-03-31 06:42:40.397223 [INFO] car_simulator_1 :: { cmd: 'unplug', args: [], exec: [Function (anonymous)] } 2024-03-31 06:42:40.776738 [INFO] evse_manager_1: :: EVSE ISO SLAC UNMATCHED 2024-03-31 06:42:40.776947 [INFO] evse_manager_1: :: EVSE ISO D-LINK_READY (false) 2024-03-31 06:42:40.821683 [INFO] evse_manager_1: :: CAR IEC Event CarUnplugged 2024-03-31 06:42:40.821873 [INFO] evse_manager_1: :: EVSE IEC Charger state: Wait for Auth->StoppingCharging 2024-03-31 06:42:40.822169 [INFO] evse_manager_1: :: EVSE IEC Set PWM Off 2024-03-31 06:42:40.864845 [INFO] evse_manager_1: :: EVSE IEC Charger state: StoppingCharging->Finished 2024-03-31 06:42:40.908928 [INFO] evse_manager_1: :: EVSE IEC Session Finished 2024-03-31 06:42:40.909130 [INFO] evse_manager_1: :: SYS Session logging stopped. ```

AC ISO 15118-2 Plug&Charge - Charges

``` 2024-03-31 06:46:07.957412 [INFO] car_simulator_1 :: { cmd: 'sleep', args: [ 1 ], exec: [Function (anonymous)] } 2024-03-31 06:46:09.209424 [INFO] car_simulator_1 :: { cmd: 'iso_wait_slac_matched', args: [], exec: [Function (anonymous)] } 2024-03-31 06:46:09.209932 [INFO] car_simulator_1 :: { cmd: 'iso_start_v2g_session', args: [ 'contract', 'ac_three_phase_core' ], exec: [Function (anonymous)] } 2024-03-31 06:46:09.253148 [INFO] car_simulator_1 :: { cmd: 'iso_wait_pwr_ready', args: [], exec: [Function (anonymous)] } 2024-03-31 06:46:09.380548 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:46:09.386507 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:46:09.421175 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:46:09.508519 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:46:09.577849 [INFO] evse_manager_1: :: SYS Session logging started. 2024-03-31 06:46:09.578009 [INFO] evse_manager_1: :: EVSE IEC Session Started: EVConnected 2024-03-31 06:46:10.583151 [INFO] iso15118_charge :: Received packet from [fe80::42:acff:fe12:4]:56375 with security 0x00 and protocol 0x00 2024-03-31 06:46:10.583196 [INFO] iso15118_charge :: SDP requested TLS, announcing TLS 2024-03-31 06:46:10.583225 [INFO] iso15118_charge :: sendto([fe80::42:acff:fe12:4]:56375) succeeded 2024-03-31 06:46:10.585169 [INFO] iso15118_charge :: Incoming connection on eth0 from [a00:d6a4:0:0:fe80::]:54948 2024-03-31 06:46:10.585308 [INFO] iso15118_charge :: Started new TLS connection thread 2024-03-31 06:46:10.629280 [INFO] evse_security:E :: Requesting certificate file: [V2G] file:"/workspace/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem" 2024-03-31 06:46:10.723148 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:46:10.765236 [INFO] iso15118_charge :: Performing TLS handshake 2024-03-31 06:46:10.771487 [INFO] iso15118_charge :: Using V2G-root of issuer V2GRootCA (SHA-1: 0x212b3816249642e4f5b855c7f1d14fc0fa4dc3b2) for TLS-handshake 2024-03-31 06:46:10.774976 [INFO] iso15118_charge :: TLS handshake succeeded 2024-03-31 06:46:10.839779 [INFO] iso15118_charge :: Handling SupportedAppProtocolReq 2024-03-31 06:46:10.840079 [INFO] iso15118_charge :: Protocol negotiation was successful. Selected protocol is ISO15118 2024-03-31 06:46:10.840505 [INFO] evse_manager_1: :: CAR ISO V2G SupportedAppProtocolReq 2024-03-31 06:46:10.885057 [INFO] evse_manager_1: :: EVSE ISO V2G SupportedAppProtocolRes 2024-03-31 06:46:11.613636 [INFO] iso15118_charge :: SessionSetupReq.EVCCID: 02:42:AC:12:00:04 2024-03-31 06:46:11.613796 [INFO] iso15118_charge :: No session_id found or not equal to the id from the preceding v2g session. Generating random session id. 2024-03-31 06:46:11.613851 [INFO] iso15118_charge :: Created new session with id 0x999763913559641562 2024-03-31 06:46:11.677894 [INFO] evse_manager_1: :: CAR ISO V2G SessionSetupReq 2024-03-31 06:46:11.712571 [INFO] evse_manager_1: :: EVSE ISO V2G SessionSetupRes 2024-03-31 06:46:12.793520 [INFO] evse_manager_1: :: CAR ISO V2G ServiceDiscoveryReq 2024-03-31 06:46:12.891706 [INFO] evse_manager_1: :: EVSE ISO V2G ServiceDiscoveryRes 2024-03-31 06:46:13.505620 [INFO] iso15118_charge :: SelectedPaymentOption: Contract 2024-03-31 06:46:13.506169 [INFO] evse_manager_1: :: CAR ISO V2G PaymentServiceSelectionReq 2024-03-31 06:46:13.604320 [INFO] evse_manager_1: :: EVSE ISO V2G PaymentServiceSelectionRes 2024-03-31 06:46:14.215650 [INFO] auth:Auth :: Received new token: { "authorization_type": "PlugAndCharge", "certificate": "-----BEGIN CERTIFICATE-----\nMIICRTCCAeqgAwIBAgICMEQwCgYIKoZIzj0EAwIwVzEiMCAGA1UEAwwZUEtJLUV4dF9DUlRfTU9fU1VCMl9WQUxJRDEQMA4GA1UECgwHRVZlcmVzdDELMAkGA1UEBhMCREUxEjAQBgoJkiaJk/IsZAEZFgJNTzAeFw0yNDAzMjkyMTUxMDJaFw0yNjAzMjkyMTUxMDJaME0xGDAWBgNVBAMMD1VLU1dJMTIzNDU2Nzg5RzEQMA4GA1UECgwHRVZlcmVzdDELMAkGA1UEBhMCREUxEjAQBgoJkiaJk/IsZAEZFgJNTzBZMBMGByqGSM49AgEGCCqGSM49AwEHA0IABJEjYSg4WB9qJpE5eCuYNj/mcsdsHeLe7ySlyxr+jiZAzeqwrNSRikQ5Eqx5qJ0SjKjlFZRpOSCJlkR4ZcAsQ6+jga8wgawwDAYDVR0TAQH/BAIwADAOBgNVHQ8BAf8EBAMCA+gwHQYDVR0OBBYEFEOuT6L+OlKn0YSukXQLCHBwLkuXMG0GCCsGAQUFBwEBBGEwXzAkBggrBgEFBQcwAYYYaHR0cHM6Ly93d3cuZXhhbXBsZS5jb20vMDcGCCsGAQUFBzAChitodHRwczovL3d3dy5leGFtcGxlLmNvbS9JbnRlcm1lZGlhdGUtQ0EuY2VyMAoGCCqGSM49BAMCA0kAMEYCIQCORTo+LrIHkmEAtnAf7Gpmlzif5nQbvGruS+Nijh59ewIhAO/jH6dwWdbUyBEnPvCop63Bcn7spYiQubwi4ei2cnWY\n-----END CERTIFICATE-----\n-----BEGIN CERTIFICATE-----\nMIICVTCCAfqgAwIBAgICMEMwCgYIKoZIzj0EAwIwVzEiMCAGA1UEAwwZUEtJLUV4dF9DUlRfTU9fU1VCMV9WQUxJRDEQMA4GA1UECgwHRVZlcmVzdDELMAkGA1UEBhMCREUxEjAQBgoJkiaJk/IsZAEZFgJNTzAeFw0yNDAzMjkyMTUxMDJaFw0yODAzMjgyMTUxMDJaMFcxIjAgBgNVBAMMGVBLSS1FeHRfQ1JUX01PX1NVQjJfVkFMSUQxEDAOBgNVBAoMB0VWZXJlc3QxCzAJBgNVBAYTAkRFMRIwEAYKCZImiZPyLGQBGRYCTU8wWTATBgcqhkjOPQIBBggqhkjOPQMBBwNCAASJZtlPa8eWnBD8Ekerx5kzHeb/0BaCYpLY2ably2tbuKoO/6hg0AvR5qaMmLnR6ZhWVsnXSO6Q+t48Aj1CeEdxo4G1MIGyMBIGA1UdEwEB/wQIMAYBAf8CAQAwDgYDVR0PAQH/BAQDAgHGMB0GA1UdDgQWBBS4s1DC/ki+wVtZKCOewNu6c0NY+TBtBggrBgEFBQcBAQRhMF8wJAYIKwYBBQUHMAGGGGh0dHBzOi8vd3d3LmV4YW1wbGUuY29tLzA3BggrBgEFBQcwAoYraHR0cHM6Ly93d3cuZXhhbXBsZS5jb20vSW50ZXJtZWRpYXRlLUNBLmNlcjAKBggqhkjOPQQDAgNJADBGAiEA3D38x+8nRs+zcpbi4I68pnYeSE2AWGoS1dsKJaFYqPECIQC4dLx7Lnm4Ozn6kEKO7XfjdioH/e8jvbYYcp4SRsQQrQ==\n-----END CERTIFICATE-----\n-----BEGIN CERTIFICATE-----\nMIICQjCCAemgAwIBAgICMEIwCgYIKoZIzj0EAwIwRjERMA8GA1UEAwwITU9Sb290Q0ExEDAOBgNVBAoMB0VWZXJlc3QxCzAJBgNVBAYTAkRFMRIwEAYKCZImiZPyLGQBGRYCTU8wHhcNMjQwMzI5MjE1MTAyWhcNMjgwMzI4MjE1MTAyWjBXMSIwIAYDVQQDDBlQS0ktRXh0X0NSVF9NT19TVUIxX1ZBTElEMRAwDgYDVQQKDAdFVmVyZXN0MQswCQYDVQQGEwJERTESMBAGCgmSJomT8ixkARkWAk1PMFkwEwYHKoZIzj0CAQYIKoZIzj0DAQcDQgAEZX75ORQ9ZOlA9/63r/wmXYldnSAdhVtKwKE/JLwBQMDpJKz0cAo5u6PfFYMnleGngvm9B4JS4F3ME6qXQLG1XqOBtTCBsjASBgNVHRMBAf8ECDAGAQH/AgEBMA4GA1UdDwEB/wQEAwIBBjAdBgNVHQ4EFgQUoFxi/Y8KcsAh/PniGtZMiyoVMMMwbQYIKwYBBQUHAQEEYTBfMCQGCCsGAQUFBzABhhhodHRwczovL3d3dy5leGFtcGxlLmNvbS8wNwYIKwYBBQUHMAKGK2h0dHBzOi8vd3d3LmV4YW1wbGUuY29tL0ludGVybWVkaWF0ZS1DQS5jZXIwCgYIKoZIzj0EAwIDRwAwRAIgVA5ki7sFnQ9JZjrMd2JLYo0D0eJhESKef8rL3WUSp30CIAJmLyWjQATx8KN+oooxQWuubUkvsp2Nwjh6Gs/UJjoM\n-----END CERTIFICATE-----\n", "connectors": [ 1 ], "id_token": { "type": "eMAID", "value": "UKSWI123456789G" } } 2024-03-31 06:46:14.215878 [INFO] evse_manager_1: :: CAR ISO V2G PaymentDetailsReq 2024-03-31 06:46:14.219137 [INFO] evse_security:E :: Building new certificate hierarchy! 2024-03-31 06:46:14.222533 [INFO] ocpp:OCPP201 :: Local contract validation result: Valid 2024-03-31 06:46:14.228207 [INFO] ocpp:OCPP201 :: Online: Pass generated OCSP data to CSMS 2024-03-31 06:46:14.312681 [INFO] evse_manager_1: :: EVSE ISO V2G PaymentDetailsRes 2024-03-31 06:46:14.316245 [INFO] ocpp:OCPP201 :: CSMS idToken status: Accepted 2024-03-31 06:46:14.316302 [INFO] ocpp:OCPP201 :: CSMS certificate status: Accepted 2024-03-31 06:46:14.317589 [INFO] auth:Auth :: Providing authorization to connector#1 2024-03-31 06:46:14.383120 [INFO] evse_manager_1: :: EVSE IEC Transaction Started (0 kWh) 2024-03-31 06:46:14.383684 [INFO] evse_manager_1: :: EVSE IEC PnC Authorization received 2024-03-31 06:46:14.383773 [INFO] evse_manager_1: :: EVSE IEC AC mode, HLC enabled, PnC auth received. We will continue with 5percent independent on how we started. 2024-03-31 06:46:14.383863 [INFO] evse_manager_1: :: EVSE IEC Charger state: Wait for Auth->PrepareCharging 2024-03-31 06:46:14.384142 [INFO] evse_manager_1: :: EVSE IEC Set PWM On (5.000000074505806%) took 0 ms 2024-03-31 06:46:14.477039 [INFO] auth:Auth :: Result for token: UKSWI123456789G: ACCEPTED 2024-03-31 06:46:14.511686 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:46:14.580708 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:46:14.581812 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:46:14.669372 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:46:15.033575 [INFO] evse_manager_1: :: CAR ISO V2G AuthorizationReq 2024-03-31 06:46:15.105786 [INFO] evse_manager_1: :: EVSE ISO V2G AuthorizationRes 2024-03-31 06:46:15.684197 [INFO] iso15118_charge :: Parameter-phase started 2024-03-31 06:46:15.684602 [INFO] iso15118_charge :: Selected energy transfer mode: AC_three_phase_core 2024-03-31 06:46:15.685207 [INFO] evse_manager_1: :: CAR ISO V2G ChargeParameterDiscoveryReq 2024-03-31 06:46:15.782705 [INFO] evse_manager_1: :: EVSE ISO V2G ChargeParameterDiscoveryRes 2024-03-31 06:46:15.979561 [INFO] car_simulator_1 :: { cmd: 'iso_draw_power_regulated', args: [ 16, 3 ], exec: [Function (anonymous)] } 2024-03-31 06:46:15.979648 [INFO] car_simulator_1 :: { cmd: 'sleep', args: [ 36000 ], exec: [Function (anonymous)] } 2024-03-31 06:46:16.223273 [INFO] evse_manager_1: :: CAR IEC Event CarRequestedPower 2024-03-31 06:46:16.401890 [INFO] iso15118_charge :: Waiting for contactor is closed 2024-03-31 06:46:16.421963 [INFO] evse_manager_1: :: CAR ISO AC HLC Close contactor 2024-03-31 06:46:16.431995 [INFO] evse_manager_1: :: EVSE IEC Charger state: PrepareCharging->Charging 2024-03-31 06:46:16.723399 [INFO] evse_manager_1: :: EVSE IEC Event PowerOn 2024-03-31 06:46:16.724491 [WARN] iso15118_charge void dlog_func(dloglevel_t, const char*, int, const char*, const char*, ...) :: Response message (type 14) not configured within 98 ms (took 324 ms) 2024-03-31 06:46:16.776941 [INFO] evse_manager_1: :: CAR ISO V2G PowerDeliveryReq 2024-03-31 06:46:16.777225 [INFO] evse_manager_1: :: EVSE ISO V2G PowerDeliveryRes 2024-03-31 06:46:17.313166 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:17.382738 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:18.102578 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:18.202570 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:18.825895 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:18.912905 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:19.459194 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:19.558023 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:19.722875 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:46:19.727786 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:46:19.765096 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:46:19.850451 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:46:20.111996 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:20.209160 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:20.747188 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:20.844813 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:21.382792 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:21.480430 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:22.014412 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:22.111570 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:22.644330 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:22.744051 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:23.308802 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:23.379557 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:23.912510 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:24.010256 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:24.563886 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:24.661253 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:24.853511 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:46:24.858173 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:46:24.892958 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:46:24.978631 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:46:25.030021 [WARN] car_simulator_1 EverestJs::Init(Napi::Env, Napi::Object):: :: Ignoring command with 0 arguments 2024-03-31 06:46:25.030147 [INFO] car_simulator_1 :: { cmd: 'iso_stop_charging', args: [], exec: [Function (anonymous)] } 2024-03-31 06:46:25.211635 [INFO] evse_manager_1: :: CAR ISO V2G ChargingStatusReq 2024-03-31 06:46:25.281177 [INFO] car_simulator_1 :: { cmd: 'iso_wait_v2g_session_stopped', args: [], exec: [Function (anonymous)] } 2024-03-31 06:46:25.324804 [INFO] evse_manager_1: :: EVSE ISO V2G ChargingStatusRes 2024-03-31 06:46:25.486409 [INFO] evse_manager_1: :: EVSE IEC Event PowerOff 2024-03-31 06:46:25.533957 [INFO] evse_manager_1: :: CAR IEC Event CarRequestedStopPower 2024-03-31 06:46:25.534137 [INFO] evse_manager_1: :: EVSE IEC Charger state: Charging->Car Paused 2024-03-31 06:46:25.878744 [INFO] evse_manager_1: :: CAR ISO AC HLC Open contactor 2024-03-31 06:46:25.879011 [INFO] evse_manager_1: :: CAR ISO V2G PowerDeliveryReq 2024-03-31 06:46:25.976951 [INFO] evse_manager_1: :: EVSE ISO V2G PowerDeliveryRes 2024-03-31 06:46:26.508029 [INFO] evse_manager_1: :: CAR ISO V2G SessionStopReq 2024-03-31 06:46:26.605692 [INFO] iso15118_charge :: v2g_dispatch_connection exited with 0 2024-03-31 06:46:26.605883 [INFO] iso15118_charge :: Closing TLS connection 2024-03-31 06:46:26.606950 [INFO] evse_manager_1: :: EVSE ISO V2G SessionStopRes 2024-03-31 06:46:28.606289 [INFO] iso15118_charge :: TLS connection closed gracefully 2024-03-31 06:46:28.606454 [INFO] iso15118_charge :: Closing TLS connection thread 2024-03-31 06:46:28.609179 [INFO] evse_manager_1: :: EVSE ISO D-LINK_TERMINATE.req 2024-03-31 06:46:28.609690 [INFO] evse_manager_1: :: EVSE IEC Set PWM Off 2024-03-31 06:46:28.689764 [INFO] evse_manager_1: :: EVSE IEC Charger state: Car Paused->StoppingCharging 2024-03-31 06:46:28.737336 [INFO] evse_manager_1: :: EVSE ISO SLAC UNMATCHED 2024-03-31 06:46:28.738065 [INFO] evse_manager_1: :: EVSE ISO D-LINK_READY (false) 2024-03-31 06:46:29.984767 [INFO] evse_security:E :: TPM Key: false 2024-03-31 06:46:29.992528 [WARN] evse_security:E static std::string evse_security::OpenSSLSupplier::x509_get_responder_url(evse_security::X509Handle*) :: Could not retrieve OCSP Responder URL from certificate 2024-03-31 06:46:30.030713 [INFO] ocpp:OCPP201 :: libocpp: Updating OCSP cache on 2 certificates 2024-03-31 06:46:30.121066 [WARN] ocpp:OCPP201 void ocpp::v201::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry. 2024-03-31 06:46:31.740524 [INFO] car_simulator_1 :: { cmd: 'unplug', args: [], exec: [Function (anonymous)] } 2024-03-31 06:46:32.221484 [INFO] evse_manager_1: :: CAR IEC Event CarUnplugged 2024-03-31 06:46:32.222054 [INFO] evse_manager_1: :: EVSE IEC Charger state: StoppingCharging->Finished 2024-03-31 06:46:32.223978 [INFO] evse_manager_1: :: EVSE IEC Transaction Finished: EVDisconnected (0 kWh) 2024-03-31 06:46:32.277066 [INFO] evse_manager_1: :: EVSE IEC Session Finished 2024-03-31 06:46:32.277184 [INFO] evse_manager_1: :: SYS Session logging stopped. ```

shankari commented 3 months ago

Since @activeshadow and @jhoshiko are both unavailable, I have pulled @sahabulh's changes and tried to run them. However, I am not able to reproduce his results. When I try "AC ISO 15118-2 PnC" from the drop down, the EVerest manager gets stuck in

2024-04-02 02:58:27.494169 [INFO] evse_manager_1:  ::                                     CAR ISO V2G AuthorizationReq
2024-04-02 02:58:27.591404 [INFO] evse_manager_1:  :: EVSE ISO V2G AuthorizationRes
2024-04-02 02:58:28.134989 [INFO] evse_manager_1:  ::                                     CAR ISO V2G AuthorizationReq
2024-04-02 02:58:28.233744 [INFO] evse_manager_1:  :: EVSE ISO V2G AuthorizationRes
2024-04-02 02:58:28.775489 [INFO] evse_manager_1:  ::                                     CAR ISO V2G AuthorizationReq
2024-04-02 02:58:28.875015 [INFO] evse_manager_1:  :: EVSE ISO V2G AuthorizationRes
2024-04-02 02:58:29.421546 [INFO] evse_manager_1:  ::                                     CAR ISO V2G AuthorizationReq

Before failing with

2024-04-02 02:58:31.358307 [ERRO] iso15118_charge void dlog_func(dloglevel_t, const char*, int, const char*, const char*, ...) :: Failed response code detected for message "Authorization", error: Response FAILED

@sahabulh are you sure these logs are from the MRE? Can you put in the exact command you used to run them? I don't see the SecurityProfile logs anywhere.

shankari commented 3 months ago

@sahabulh @activeshadow @jhoshiko ah this was just because the maeve image was not rebuilt, and so hadn't picked up the changes to ignore the OCSP. After deleting the existing maeve images, they were rebuilt, and the demo went to the correct charging status. However, the power drawn is still shown as 0W. @sahabulh I believe you said that this was a known issue that you were planning to fix....

shankari commented 3 months ago

Power draw shown as 0 is apparently due to an error with the EV simulator. https://lfenergy.zulipchat.com/#narrow/stream/417676-EVerest.3A-Cloud-communication/topic/Enable.20PnC.20with.20OCPP.202.2E0.2E1/near/430745417

Merging this now...

EVerest / everest-demo

Enable OCPP 2.0.1 with AC ISO 15118-2, PnC and MaEVe #38