ESDP TLS patches

[catarial]

Incorporating the TLS changes for ESDP from https://github.com/NREL/ext-switchev-iso15118

I merged the fork with the 2025.5.0 tag and added a patch based on that tag to the library patches. The changes I made can be seen here main...catarial:everest-demo:esdp-tls

I ran the iso15118-dc-demo script and the ESDP logs say it requested NO-TLS so that leads me to believe there is something else I have to configure.

iso15118-dc-charging-session.log

[catarial]

I ran the demo using the OCPP201 config which has tis_active set to true and set the security profile to level 3. I get a message from Maeve that TLS should be active.

Running with SP3, TLS should be enabled

However I am still seeing the ESDP logs announcing NO-TLS

2025-08-18 14:59:28.946656 [INFO] iso15118_charge :: ESDP requested NO-TLS, announcing NO-TLS

ocpp201-sec-profile-3.log

[shankari]

@catarial there are two separate TLS connections:

1. between the car and the station
2. between the station and the CSMS

The maeve log (and SP3) refer to (2)
The ESDP log refers to (1)

Regardless, if you have tls_active, ESDP should be using TLS. Can you add logs to the ESDP code where it checks TLS?
Alternatively, you can remove the ESDP patches and see if the base SDP turns on TLS, and then restore ESDP and see if it turns on TLS

[catarial]

So I did something silly and I forgot to switch the image in the docker compose file, so my previous test was using the default image. I ran another test and now it's crashing during the ESDP setup.

2025-08-18 18:54:35.980475 [INFO] iso15118_charge  :: ESDP requested NO-TLS, announcing NO-TLS

2025-08-18 18:54:35.980526 [INFO] iso15118_charge  :: ESDP extensions data encoded: 369 bytes

2025-08-18 18:54:35.980561 [INFO] iso15118_charge  :: sendto([fe80::d050:41ff:fe6e:d571]:49187) succeeded for ESDP

2025-08-18 18:54:35.980565 [INFO] iso15118_charge  :: Size of ESDPRes message sent: 381 bytes
2025-08-18 18:54:35.988657 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Received datagram from UDP server at address ('fe80::d050:41ff:fe6e:d571', 15200, 0, 11)

2025-08-18 18:54:35.990990 [INFO] iso15118_charge  :: Incoming connection on eth0 from [a00:8afc:0:0:fe80::]:35580

2025-08-18 18:54:35.991382 [INFO] iso15118_charge  :: Started new TCP connection thread

2025-08-18 18:54:35.991487 [ERRO] iso15118_charge void dlog_func(dloglevel_t, const char*, int, const char*, const char*, ...) :: Invalid v2gtp header

2025-08-18 18:54:35.991509 [ERRO] iso15118_charge void dlog_func(dloglevel_t, const char*, int, const char*, const char*, ...) :: v2g_incoming_v2gtp() failed

2025-08-18 18:54:35.991520 [INFO] iso15118_charge  :: v2g_handle_connection exited with -1

2025-08-18 18:54:35.991534 [INFO] iso15118_charge  :: Closing TCP connection
2025-08-18 18:54:38.002000 [ERRO] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: ConnectionResetError when trying to connect to host fe80::d050:41ff:fe6e:d571 and port 61341
Traceback (most recent call last):
  File "/ext/dist/libexec/everest/modules/PyEvJosev/../../3rd_party/josev/iso15118/evcc/comm_session_handler.py", line 466, in start_comm_session
    self.tcp_client = await TCPClient.create(
                      ^^^^^^^^^^^^^^^^^^^^^^^
  File "/ext/dist/libexec/everest/modules/PyEvJosev/../../3rd_party/josev/iso15118/evcc/transport/tcp_client.py", line 62, in create
    raise exc
  File "/ext/dist/libexec/everest/modules/PyEvJosev/../../3rd_party/josev/iso15118/evcc/transport/tcp_client.py", line 53, in create
    self.reader, self.writer = await asyncio.open_connection(
                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/asyncio/streams.py", line 48, in open_connection
    transport, _ = await loop.create_connection(
                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/asyncio/base_events.py", line 1112, in create_connection
    transport, protocol = await self._create_connection_transport(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/asyncio/base_events.py", line 1145, in _create_connection_transport
    await waiter
  File "/usr/lib/python3.11/asyncio/sslproto.py", line 574, in _on_handshake_complete
    raise handshake_exc
ConnectionResetError

full everest logs

> ./demo-iso15118-2-ocpp-201.sh -r $PWD -3
DEMO REPO:        /Users/anolan/src/everest-demo
DEMO BRANCH:      main
DEMO VERSION:     v2.0.1-sp3
DEMO CONFIG:      docker-compose.ocpp201.yml
DEMO DIR:         /var/folders/72/0q297ld15v9c7tp4_1mlkvmrw82w94/T/tmp.JYMuXfNK1I
DEMO CSMS:        maeve
CHARGE STATION:   cp001
Cloning EVerest from /Users/anolan/src/everest-demo into /var/folders/72/0q297ld15v9c7tp4_1mlkvmrw82w94/T/tmp.JYMuXfNK1I/everest-demo
/var/folders/72/0q297ld15v9c7tp4_1mlkvmrw82w94/T/tmp.JYMuXfNK1I/everest-demo/maeve /var/folders/72/0q297ld15v9c7tp4_1mlkvmrw82w94/T/tmp.JYMuXfNK1I
Copying certs into /var/folders/72/0q297ld15v9c7tp4_1mlkvmrw82w94/T/tmp.JYMuXfNK1I/maeve-csms/config/certificates
Validating that the certificates are set up correctly
config/certificates/csms.pem: OK
Chain:
depth=0: CN=host.docker.internal, O=EVerest, C=DE, DC=CPO (untrusted)
depth=1: CN=CPOSubCA2, O=EVerest, C=DE, DC=V2G (untrusted)
depth=2: CN=CPOSubCA1, O=EVerest, C=DE, DC=V2G (untrusted)
depth=3: CN=V2GRootCA, O=EVerest, C=DE, DC=V2G
Patching the CSMS to enable EVerest organization
patching file docker-compose.yml
Patching the CSMS to enable local mo root
patching file 'config/manager/config.toml'
CSMS_SP1_BASE:     ws://host.docker.internal/ws
CSMS_SP2_BASE:     wss://host.docker.internal/ws
CSMS_SP3_BASE:     wss://host.docker.internal/ws
WARN[0000] The "UID" variable is not set. Defaulting to a blank string.
WARN[0000] The "GID" variable is not set. Defaulting to a blank string.
WARN[0000] The "UID" variable is not set. Defaulting to a blank string.
WARN[0000] The "GID" variable is not set. Defaulting to a blank string.
[+] Running 5/5
 ✔ Network maeve-csms                Created                                                                                                                                                                                     0.1s
 ✔ Container maeve-csms-firestore-1  Healthy                                                                                                                                                                                    23.1s
 ✔ Container maeve-csms-mqtt-1       Healthy                                                                                                                                                                                    23.1s
 ✔ Container maeve-csms-manager-1    Healthy                                                                                                                                                                                    23.0s
 ✔ Container maeve-csms-gateway-1    Healthy                                                                                                                                                                                    32.9s
Waiting 5s for maeve services to finish starting...
Adding a charger and RFID card to maeve
While running subscript, DEMO_VERSION is  v2.0.1-sp3 and cp001
MaEVe CSMS started, adding charge station with Security Profile 3 (note: profiles in MaEVe start with 0 so SP-2 == OCPP SP-3)
Charge station added, adding user token
/var/folders/72/0q297ld15v9c7tp4_1mlkvmrw82w94/T/tmp.JYMuXfNK1I
/var/folders/72/0q297ld15v9c7tp4_1mlkvmrw82w94/T/tmp.JYMuXfNK1I/everest-demo /var/folders/72/0q297ld15v9c7tp4_1mlkvmrw82w94/T/tmp.JYMuXfNK1I
API calls to CSMS finished, Starting everest
WARN[0000] /var/folders/72/0q297ld15v9c7tp4_1mlkvmrw82w94/T/tmp.JYMuXfNK1I/everest-demo/docker-compose.ocpp201.yml: the attribute `version` is obsolete, it will be ignored, please remove it to avoid potential confusion
[+] Running 4/4
 ✔ Network everest-ac-demo                  Created                                                                                                                                                                              0.1s
 ✔ Container everest-ac-demo-mqtt-server-1  Healthy                                                                                                                                                                              2.0s
 ✔ Container everest-ac-demo-nodered-1      Healthy                                                                                                                                                                             31.9s
 ✔ Container everest-ac-demo-manager-1      Healthy                                                                                                                                                                              1.9s
Successfully copied 7.68kB to everest-ac-demo-manager-1:/ext/source/config/config-sil-ocpp201-pnc.yaml
Successfully copied 7.68kB to everest-ac-demo-manager-1:/tmp/config-sil-ocpp201-pnc.yaml
Applying generic OCPP configuration with v2.0.1-sp3, ws://host.docker.internal/ws and cp001
TLS over ISO enabled, expanding certs.
/ext /workspace
Configured everest certs, validating that the chain is set up correctly
dist/etc/everest/certs/client/csms/CSMS_LEAF.pem: OK
Chain:
depth=0: CN = host.docker.internal, O = EVerest, C = DE, DC = CPO (untrusted)
depth=1: CN = CPOSubCA2, O = EVerest, C = DE, DC = V2G (untrusted)
depth=2: CN = CPOSubCA1, O = EVerest, C = DE, DC = V2G (untrusted)
depth=3: CN = V2GRootCA, O = EVerest, C = DE, DC = V2G
/workspace
rm: cannot remove '/ext/dist/share/everest/modules/OCPP201/component_config/custom/EVSE_2.json': No such file or directory
rm: cannot remove '/ext/dist/share/everest/modules/OCPP201/component_config/custom/Connector_2_1.json': No such file or directory
TLS over OCPP enabled, expanding certs.
/ext /workspace
Configured everest certs, validating that the chain is set up correctly
dist/etc/everest/certs/client/csms/CSMS_LEAF.pem: OK
Chain:
depth=0: CN = host.docker.internal, O = EVerest, C = DE, DC = CPO (untrusted)
depth=1: CN = CPOSubCA2, O = EVerest, C = DE, DC = V2G (untrusted)
depth=2: CN = CPOSubCA1, O = EVerest, C = DE, DC = V2G (untrusted)
depth=3: CN = V2GRootCA, O = EVerest, C = DE, DC = V2G
/workspace
Running with SP3, TLS should be enabled
Configured to SecurityProfile: 2, configuring server to  wss://host.docker.internal/ws/cp001
Verifying security settings for v2.0.1-sp3
{
  "type": "Actual",
  "mutability": "ReadOnly",
  "value": 3
}
{
  "configurationSlot": 1,
  "connectionData": {
    "messageTimeout": 30,
    "ocppCsmsUrl": "wss://host.docker.internal/ws/cp001",
    "ocppInterface": "Wired0",
    "ocppTransport": "JSON",
    "ocppVersion": "OCPP20",
    "securityProfile": 3
  }
}
Starting software in the loop simulation automatically
2025-08-18 18:54:10.345667 [INFO] manager          ::   ________      __                _
2025-08-18 18:54:10.346022 [INFO] manager          ::  |  ____\ \    / /               | |
2025-08-18 18:54:10.346045 [INFO] manager          ::  | |__   \ \  / /__ _ __ ___  ___| |_
2025-08-18 18:54:10.346059 [INFO] manager          ::  |  __|   \ \/ / _ \ '__/ _ \/ __| __|
2025-08-18 18:54:10.346068 [INFO] manager          ::  | |____   \  /  __/ | |  __/\__ \ |_
2025-08-18 18:54:10.346075 [INFO] manager          ::  |______|   \/ \___|_|  \___||___/\__|
2025-08-18 18:54:10.346085 [INFO] manager          ::
2025-08-18 18:54:10.346093 [INFO] manager          :: everest-framework 0.22.1 @v0.22.1
2025-08-18 18:54:10.346104 [INFO] manager          :: everest-core 2025.6.0 @2025.6.0-dirty
2025-08-18 18:54:10.346114 [INFO] manager          ::
2025-08-18 18:54:10.346121 [INFO] manager          :: Using MQTT broker mqtt-server:1883
2025-08-18 18:54:10.362149 [INFO] everest_ctrl     :: Launching controller service on port 8849
2025-08-18 18:54:10.386642 [INFO] manager          :: Boot mode is set to YamlFile, loading module configs from YAML file
2025-08-18 18:54:10.386698 [INFO] manager          :: Loading config file at: /ext/source/config/config-sil-ocpp201-pnc.yaml
2025-08-18 18:54:10.518919 [INFO] manager          :: Config loading completed in 168ms
2025-08-18 18:54:10.523107 [INFO] manager          :: Starting 17 modules
2025-08-18 18:54:11.662430 [DEBG] iso15118_car    void Everest::Logging::init(const std::string&, std::string) :: Logger initialized (using /ext/dist/etc/everest/default_logging.cfg)...
2025-08-18 18:54:11.663475 [DEBG] iso15118_car    Everest::MQTTAbstractionImpl::MQTTAbstractionImpl(const std::string&, const std::string&, const std::string&, const std::string&) :: Initializing MQTT abstraction layer...
2025-08-18 18:54:11.664313 [DEBG] iso15118_car    bool Everest::MQTTAbstractionImpl::connect() :: Connecting to MQTT broker: mqtt-server:1883
2025-08-18 18:54:11.666459 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::on_mqtt_connect() :: Connected to MQTT broker
2025-08-18 18:54:11.666534 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::on_mqtt_connect() :: Subscribing to needed MQTT topics...
2025-08-18 18:54:11.666660 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3ae3f608 on topic everest/modules/iso15118_car/response
2025-08-18 18:54:11.712723 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interfaces
2025-08-18 18:54:11.758350 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/ISO15118_charger
2025-08-18 18:54:11.766131 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/ISO15118_ev
2025-08-18 18:54:11.826391 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/ac_rcd
2025-08-18 18:54:11.870132 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/auth
2025-08-18 18:54:11.923613 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/auth_token_provider
2025-08-18 18:54:11.968166 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/auth_token_validator
2025-08-18 18:54:12.014850 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/car_simulator
2025-08-18 18:54:12.057510 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/connector_lock
2025-08-18 18:54:12.058060 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/empty
2025-08-18 18:54:12.103259 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/energy
2025-08-18 18:54:12.146489 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/energy_manager
2025-08-18 18:54:12.193465 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/error_history
2025-08-18 18:54:12.235668 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3b1d1488 on topic everest/interface_definitions/ev_board_support
2025-08-18 18:54:12.286632 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84005cb8 on topic everest/interface_definitions/ev_manager
2025-08-18 18:54:12.335349 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84005cb8 on topic everest/interface_definitions/ev_slac
2025-08-18 18:54:12.389431 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84005cb8 on topic everest/interface_definitions/evse_board_support
2025-08-18 18:54:12.392007 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/evse_manager
2025-08-18 18:54:12.396247 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/evse_security
2025-08-18 18:54:12.397679 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/external_energy_limits
2025-08-18 18:54:12.438497 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/iso15118_extensions
2025-08-18 18:54:12.487588 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/ocpp
2025-08-18 18:54:12.491546 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/ocpp_data_transfer
2025-08-18 18:54:12.541008 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/powermeter
2025-08-18 18:54:12.584409 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/reservation
2025-08-18 18:54:12.626430 [INFO] car_simulator_1  :: Module car_simulator_1 initialized [2077ms]
2025-08-18 18:54:12.638559 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/session_cost2025-08-18 18:54:12.643450 [INFO] evse_manager_1:  :: Module evse_manager_1 initialized [2052ms]

2025-08-18 18:54:12.688350 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/slac
2025-08-18 18:54:12.720751 [INFO] auth:Auth        :: Module auth initialized [2124ms]
2025-08-18 18:54:12.732848 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/system
2025-08-18 18:54:12.755073 [INFO] error_history:E  :: Resetting database
2025-08-18 18:54:12.768241 [INFO] error_history:E  :: Module error_history initialized [2168ms]
2025-08-18 18:54:12.779775 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff8400c528 on topic everest/interface_definitions/uk_random_delay
2025-08-18 18:54:12.820294 [INFO] grid_connection  :: Module grid_connection_point initialized [2219ms]
2025-08-18 18:54:12.821291 [INFO] evse_manager_1_  :: Module evse_manager_1_api_sink initialized [2158ms]
2025-08-18 18:54:12.828419 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3ad265e8 on topic everest/types
2025-08-18 18:54:12.859073 [INFO] system:System    :: Module system initialized [2109ms]
2025-08-18 18:54:12.868760 [INFO] evse_security:E  :: Module evse_security initialized [2267ms]
2025-08-18 18:54:12.870087 [INFO] evse_manager_1_  :: Module evse_manager_1_ocpp_sink initialized [2261ms]
2025-08-18 18:54:12.871028 [INFO] energy_manager:  :: Module energy_manager initialized [2288ms]
2025-08-18 18:54:12.874835 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3ad265e8 on topic everest/settings
2025-08-18 18:54:12.877453 [INFO] iso15118_charge  :: Module iso15118_charger initialized [2182ms]
2025-08-18 18:54:12.889666 [INFO] token_provider_  :: Module token_provider_1 initialized [2078ms]
2025-08-18 18:54:12.909954 [INFO] slac:SlacSimula  :: Module slac initialized [2132ms]
2025-08-18 18:54:12.919312 [INFO] api:API          :: Module api initialized [2363ms]
2025-08-18 18:54:12.927043 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3ad265e8 on topic everest/module_names
2025-08-18 18:54:12.928854 [INFO] yeti_driver_1:Y  :: Module yeti_driver_1 initialized [2218ms]
2025-08-18 18:54:12.972530 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0x3ad265e8 on topic everest/manifests/API
2025-08-18 18:54:12.997136 [INFO] ocpp:OCPP201     :: Module ocpp initialized [2195ms]
2025-08-18 18:54:13.018342 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84011218 on topic everest/manifests/Auth
2025-08-18 18:54:13.063678 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84011218 on topic everest/manifests/EvManager
2025-08-18 18:54:13.107818 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff840117c8 on topic everest/manifests/EnergyManager
2025-08-18 18:54:13.150463 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84011998 on topic everest/manifests/ErrorHistory
2025-08-18 18:54:13.194957 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84011998 on topic everest/manifests/EvseManager
2025-08-18 18:54:13.196248 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84011998 on topic everest/manifests/EnergyNode
2025-08-18 18:54:13.238636 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84011998 on topic everest/manifests/EnergyNode
2025-08-18 18:54:13.283151 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84011998 on topic everest/manifests/EvseSecurity
2025-08-18 18:54:13.327323 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84011998 on topic everest/manifests/EnergyNode
2025-08-18 18:54:13.370880 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84011998 on topic everest/manifests/PyEvJosev
2025-08-18 18:54:13.414403 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84013178 on topic everest/manifests/EvseV2G
2025-08-18 18:54:13.458434 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84013308 on topic everest/manifests/OCPP201
2025-08-18 18:54:13.460169 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84013308 on topic everest/manifests/SlacSimulator
2025-08-18 18:54:13.504796 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84013308 on topic everest/manifests/System
2025-08-18 18:54:13.551079 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84013308 on topic everest/manifests/DummyTokenProviderManual
2025-08-18 18:54:13.595421 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering get config MQTT handler 0xffff84013308 on topic everest/manifests/YetiSimulator
2025-08-18 18:54:13.642655 [DEBG] iso15118_car    Everest::Everest::Everest(std::string, const Everest::Config&, bool, std::shared_ptr<Everest::MQTTAbstraction>, const std::string&, bool, bool) :: Initializing EVerest framework...
2025-08-18 18:54:13.642832 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering external MQTT handler 0x3b4552e8 on topic everest/ready
2025-08-18 18:54:13.643861 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering call handler 0xffff84011998 for command enable_sae_j2847_v2g_v2h on topic everest/modules/iso15118_car/impl/ev/cmd
2025-08-18 18:54:13.644012 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering call handler 0xffff84011218 for command pause_charging on topic everest/modules/iso15118_car/impl/ev/cmd
2025-08-18 18:54:13.644062 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering call handler 0xffff84013178 for command set_bpt_dc_params on topic everest/modules/iso15118_car/impl/ev/cmd
2025-08-18 18:54:13.644097 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering call handler 0x3b455508 for command set_dc_params on topic everest/modules/iso15118_car/impl/ev/cmd
2025-08-18 18:54:13.644127 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering call handler 0x3b455f68 for command set_fault on topic everest/modules/iso15118_car/impl/ev/cmd
2025-08-18 18:54:13.644179 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering call handler 0x3b2918f8 for command start_charging on topic everest/modules/iso15118_car/impl/ev/cmd
2025-08-18 18:54:13.644224 [DEBG] iso15118_car    void Everest::MQTTAbstractionImpl::register_handler(const std::string&, std::shared_ptr<TypedHandler>, QOS) :: Registering call handler 0x3b294da8 for command stop_charging on topic everest/modules/iso15118_car/impl/ev/cmd
2025-08-18 18:54:13.644347 [INFO] iso15118_car     :: Module iso15118_car initialized [1981ms]
2025-08-18 18:54:13.688313 [INFO] manager          :: Clearing retained topics published by manager during startup
2025-08-18 18:54:13.688550 [INFO] manager          :: 🚙🚙🚙 All modules are initialized. EVerest up and running [3352ms] 🚙🚙🚙

2025-08-18 18:54:13.693953 [INFO] iso15118_charge  :: TCP server on eth0 is listening on port [fe80::d050:41ff:fe6e:d571%11]:61341

2025-08-18 18:54:13.694038 [INFO] iso15118_charge  :: TLS server on eth0 is listening on port [fe80::d050:41ff:fe6e:d571%11]:64109
2025-08-18 18:54:13.704414 [INFO] evse_security:E  :: Requesting leaf certificate info: V2G
2025-08-18 18:54:13.707464 [INFO] evse_security:E  :: Found valid leaf: ["/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"]
2025-08-18 18:54:13.716832 [INFO] evse_security:E  :: Found valid leaf: ["/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"]
2025-08-18 18:54:13.717677 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem"
2025-08-18 18:54:13.717688 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:13.717796 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"
2025-08-18 18:54:13.717805 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.717909 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA2
2025-08-18 18:54:13.718032 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA1
2025-08-18 18:54:13.718130 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.der"
2025-08-18 18:54:13.718140 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"
2025-08-18 18:54:13.718145 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.723873 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem"
2025-08-18 18:54:13.723999 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:13.724118 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"
2025-08-18 18:54:13.724152 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.724275 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA2
2025-08-18 18:54:13.724391 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA1
2025-08-18 18:54:13.724512 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.der"
2025-08-18 18:54:13.724547 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"
2025-08-18 18:54:13.724568 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.731269 [DEBG] iso15118_car    void Everest::Everest::handle_ready(const Everest::json&) :: handle_ready: true
2025-08-18 18:54:13.731722 [DEBG] iso15118_car    void Everest::Everest::handle_ready(const Everest::json&) :: Framework now ready to process events, calling module ready handler
2025-08-18 18:54:13.731771 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: ready!
2025-08-18 18:54:13.741091 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem"
2025-08-18 18:54:13.741201 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:13.741611 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"
2025-08-18 18:54:13.741724 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.742101 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA2
2025-08-18 18:54:13.742531 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA1
2025-08-18 18:54:13.742856 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.der"
2025-08-18 18:54:13.742994 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"
2025-08-18 18:54:13.743014 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.746754 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem"
2025-08-18 18:54:13.746829 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:13.746971 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"
2025-08-18 18:54:13.747017 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.747128 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA2
2025-08-18 18:54:13.747254 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA1
2025-08-18 18:54:13.747374 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.der"
2025-08-18 18:54:13.747405 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"
2025-08-18 18:54:13.747434 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.751544 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem"
2025-08-18 18:54:13.751639 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:13.751760 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"
2025-08-18 18:54:13.751804 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.751967 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA2
2025-08-18 18:54:13.752110 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA1
2025-08-18 18:54:13.752233 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.der"
2025-08-18 18:54:13.752265 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"
2025-08-18 18:54:13.752293 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.755603 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem"
2025-08-18 18:54:13.755664 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:13.755773 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"
2025-08-18 18:54:13.755803 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.755963 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA2
2025-08-18 18:54:13.756095 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA1
2025-08-18 18:54:13.756203 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.der"
2025-08-18 18:54:13.756225 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"
2025-08-18 18:54:13.756252 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.765330 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem"
2025-08-18 18:54:13.767348 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:13.767517 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"
2025-08-18 18:54:13.767530 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.767644 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA2
2025-08-18 18:54:13.767754 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA1
2025-08-18 18:54:13.767855 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.der"
2025-08-18 18:54:13.767863 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"
2025-08-18 18:54:13.767868 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.772242 [INFO] ocpp:OCPP201     :: Target version: 3, current version: 0
2025-08-18 18:54:13.773189 [INFO] ocpp:OCPP201     :: Migration files to apply:
2025-08-18 18:54:13.773237 [INFO] ocpp:OCPP201     :: Migration file [up] version 1, path: /ext/dist/share/everest/modules/OCPP201/device_model_migrations/1_up-initial.sql
2025-08-18 18:54:13.773263 [INFO] ocpp:OCPP201     :: Migration file [up] version 2, path: /ext/dist/share/everest/modules/OCPP201/device_model_migrations/2_up-variable_source.sql
2025-08-18 18:54:13.773292 [INFO] ocpp:OCPP201     :: Migration file [up] version 3, path: /ext/dist/share/everest/modules/OCPP201/device_model_migrations/3_up-variable_required.sql
2025-08-18 18:54:13.784991 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem"
2025-08-18 18:54:13.785049 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:13.785163 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"
2025-08-18 18:54:13.785176 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.785290 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA2
2025-08-18 18:54:13.785393 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA1
2025-08-18 18:54:13.785495 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.der"
2025-08-18 18:54:13.785504 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"
2025-08-18 18:54:13.785508 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:13.791559 [WARN] auth:Auth       void module::ReservationHandler::load_reservations() :: Can not load reservations because no storage was configured.
2025-08-18 18:54:13.814277 [INFO] ocpp:OCPP201     :: Established connection to device model database: "/ext/dist/share/everest/modules/OCPP201/device_model_storage.db"
2025-08-18 18:54:13.814570 [INFO] ocpp:OCPP201     :: Database file "/ext/dist/share/everest/modules/OCPP201/device_model_storage.db" is already closed
2025-08-18 18:54:13.852699 [WARN] iso15118_charge void {anonymous}::log_handler(openssl::log_level_t, const std::string&) :: <n> certificates != <n> OCSP responses

2025-08-18 18:54:13.878210 [INFO] iso15118_charge  :: SDP socket setup succeeded

2025-08-18 18:54:13.878612 [INFO] iso15118_charge  :: ESDP socket setup succeeded

2025-08-18 18:54:13.907941 [WARN] iso15118_charge void dlog_func(dloglevel_t, const char*, int, const char*, const char*, ...) :: Removed DIN70121 from the list of supported protocols as AC is enabled
2025-08-18 18:54:13.977354 [INFO] evse_manager_1:  :: AC HLC mode enabled.
2025-08-18 18:54:13.990396 [INFO] ocpp:OCPP201     :: Target version: 3, current version: 0
2025-08-18 18:54:13.990902 [INFO] ocpp:OCPP201     :: Migration files to apply:
2025-08-18 18:54:13.996073 [INFO] ocpp:OCPP201     :: Migration file [up] version 1, path: /ext/dist/share/everest/modules/OCPP201/device_model_migrations/1_up-initial.sql
2025-08-18 18:54:13.996102 [INFO] ocpp:OCPP201     :: Migration file [up] version 2, path: /ext/dist/share/everest/modules/OCPP201/device_model_migrations/2_up-variable_source.sql
2025-08-18 18:54:13.996112 [INFO] ocpp:OCPP201     :: Migration file [up] version 3, path: /ext/dist/share/everest/modules/OCPP201/device_model_migrations/3_up-variable_required.sql
2025-08-18 18:54:14.024210 [INFO] ocpp:OCPP201     :: Established connection to device model database: "/ext/dist/share/everest/modules/OCPP201/everest_device_model_storage.db"
2025-08-18 18:54:14.143194 [INFO] ocpp:OCPP201     :: Database file "/ext/dist/share/everest/modules/OCPP201/everest_device_model_storage.db" is already closed
2025-08-18 18:54:14.163023 [INFO] ocpp:OCPP201     :: Successfully retrieved Device Model from DeviceModelStorage
2025-08-18 18:54:14.169939 [INFO] ocpp:OCPP201     :: Successfully retrieved Device Model from DeviceModelStorage
2025-08-18 18:54:14.170876 [INFO] ocpp:OCPP201     :: Successfully retrieved Device Model from DeviceModelStorage
2025-08-18 18:54:14.192345 [INFO] ocpp:OCPP201     :: Successfully retrieved Device Model from DeviceModelStorage
2025-08-18 18:54:14.198564 [INFO] ocpp:OCPP201     :: Target version: 6, current version: 0
2025-08-18 18:54:14.199513 [INFO] ocpp:OCPP201     :: Migration files to apply:
2025-08-18 18:54:14.199586 [INFO] ocpp:OCPP201     :: Migration file [up] version 1, path: /ext/dist/share/everest/modules/OCPP201/core_migrations/1_up-initial.sql
2025-08-18 18:54:14.199623 [INFO] ocpp:OCPP201     :: Migration file [up] version 2, path: /ext/dist/share/everest/modules/OCPP201/core_migrations/2_up-auth_cache_management.sql
2025-08-18 18:54:14.199659 [INFO] ocpp:OCPP201     :: Migration file [up] version 3, path: /ext/dist/share/everest/modules/OCPP201/core_migrations/3_up-persist-normal-messages.sql
2025-08-18 18:54:14.199695 [INFO] ocpp:OCPP201     :: Migration file [up] version 4, path: /ext/dist/share/everest/modules/OCPP201/core_migrations/4_up-transactions_db.sql
2025-08-18 18:54:14.199724 [INFO] ocpp:OCPP201     :: Migration file [up] version 5, path: /ext/dist/share/everest/modules/OCPP201/core_migrations/5_up-charging_profiles_db.sql
2025-08-18 18:54:14.199758 [INFO] ocpp:OCPP201     :: Migration file [up] version 6, path: /ext/dist/share/everest/modules/OCPP201/core_migrations/6_up-charging_profiles_source_tx_id.sql
2025-08-18 18:54:14.212149 [INFO] evse_manager_1:  :: Ignoring BSP Event, BSP is not enabled yet.
2025-08-18 18:54:14.264443 [INFO] evse_manager_1:  :: Cleaning up any other transaction on start up
2025-08-18 18:54:14.314172 [INFO] ocpp:OCPP201     :: Logging OCPP messages to log file: /tmp/everest_ocpp_logs/2025-08-18T18:54:14.314Z.log
2025-08-18 18:54:14.314303 [INFO] ocpp:OCPP201     :: Logging OCPP messages to html file: /tmp/everest_ocpp_logs/2025-08-18T18:54:14.314Z.html
2025-08-18 18:54:14.314416 [INFO] ocpp:OCPP201     :: Logging SecurityEvents to file: /tmp/everest_ocpp_logs/2025-08-18T18:54:14.314Z.security.log
2025-08-18 18:54:14.328474 [WARN] ocpp:OCPP201    void ocpp::v2::MonitoringUpdater::start_monitoring() :: Attempted to start monitoring without 'MonitoringCtrlrEnabled'
2025-08-18 18:54:14.364673 [INFO] ocpp:OCPP201     :: About to publish composite charging schedules:
2025-08-18 18:54:14.364732 [INFO] ocpp:OCPP201     :: {
    "chargingRateUnit": "A",
    "chargingSchedulePeriod": [
        {
            "limit": 16.0,
            "startPeriod": 0
        }
    ],
    "duration": 600,
    "evseId": 0,
    "scheduleStart": "2025-08-18T18:54:14.000Z"
}
2025-08-18 18:54:14.364879 [INFO] ocpp:OCPP201     :: {
    "chargingRateUnit": "A",
    "chargingSchedulePeriod": [
        {
            "limit": 16.0,
            "startPeriod": 0
        }
    ],
    "duration": 600,
    "evseId": 1,
    "scheduleStart": "2025-08-18T18:54:14.000Z"
}
2025-08-18 18:54:14.368456 [INFO] api:API          :: Received OCPP charging schedule update: {"schedules":[{"charging_rate_unit":"A","charging_schedule_period":[{"limit":16.0,"start_period":0}],"duration":600,"evse":0,"start_schedule":"2025-08-18T18:54:14.000Z"},{"charging_rate_unit":"A","charging_schedule_period":[{"limit":16.0,"start_period":0}],"duration":600,"evse":1,"start_schedule":"2025-08-18T18:54:14.000Z"}]}
2025-08-18 18:54:14.490939 [INFO] evse_manager_1:  :: 🌀🌀🌀 Ready to start charging 🌀🌀🌀
2025-08-18 18:54:14.497346 [INFO] ocpp:OCPP201     :: EVSE 1 ready.
2025-08-18 18:54:14.634322 [INFO] ocpp:OCPP201     :: TxStartPoints from device model: PowerPathClosed
2025-08-18 18:54:14.634786 [INFO] ocpp:OCPP201     :: TxStopPoints from device model: EVConnected,Authorized
2025-08-18 18:54:14.638061 [INFO] ocpp:OCPP201     :: All EVSE ready. Starting OCPP2.0.1 service
2025-08-18 18:54:14.691813 [INFO] ocpp:OCPP201     :: Found 0 evse in the database
2025-08-18 18:54:14.710179 [INFO] ocpp:OCPP201     :: Security Event in OCPP occurred: StartupOfTheDevice
2025-08-18 18:54:14.777115 [WARN] evse_manager_1: virtual bool module::evse::evse_managerImpl::handle_external_ready_to_start_charging() :: Ignoring external ready to start charging command, this could be a configuration issue. Please check if 'external_ready_to_start_charging' is set to true if you want to use this feature.
2025-08-18 18:54:14.781649 [INFO] ocpp:OCPP201     :: Open websocket with NetworkConfigurationPriority: 1 which is configurationSlot 1
2025-08-18 18:54:14.786415 [INFO] ocpp:OCPP201     :: Starting connection attempts to uri: wss://host.docker.internal/ws/cp001 with security-profile 3
2025-08-18 18:54:14.786638 [INFO] ocpp:OCPP201     :: Init client loop with ID: ffff5b40f180
2025-08-18 18:54:14.799879 [INFO] evse_security:E  :: Requesting leaf certificate info: CSMS
2025-08-18 18:54:14.807384 [INFO] evse_security:E  :: Found valid leaf: ["/ext/dist/etc/everest/certs/client/csms/CSMS_LEAF.pem"]
2025-08-18 18:54:14.807551 [INFO] evse_security:E  :: Not requiring all valid leafs, returning
2025-08-18 18:54:14.853495 [INFO] evse_security:E  :: Building new certificate hierarchy!
2025-08-18 18:54:14.855170 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/csms/CSMS_ROOT_CA.pem"
2025-08-18 18:54:14.855207 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:14.855340 [INFO] evse_security:E  :: SHANKARI: There are 1 certificates, which are:
2025-08-18 18:54:14.855372 [INFO] evse_security:E  :: SHANKARI: Certificate V2GRootCAself_signed: truewith path "/ext/dist/etc/everest/certs/ca/csms/CSMS_ROOT_CA.pem"
2025-08-18 18:54:14.855430 [INFO] evse_security:E  :: -----BEGIN CERTIFICATE-----
MIIBwjCCAWigAwIBAgICMDkwCgYIKoZIzj0EAwIwSDESMBAGA1UEAwwJVjJHUm9v
dENBMRAwDgYDVQQKDAdFVmVyZXN0MQswCQYDVQQGEwJERTETMBEGCgmSJomT8ixk
ARkWA1YyRzAeFw0yNDAzMTIwMTQzMDlaFw0zNDAzMTAwMTQzMDlaMEgxEjAQBgNV
BAMMCVYyR1Jvb3RDQTEQMA4GA1UECgwHRVZlcmVzdDELMAkGA1UEBhMCREUxEzAR
BgoJkiaJk/IsZAEZFgNWMkcwWTATBgcqhkjOPQIBBggqhkjOPQMBBwNCAASstPOH
V2vd+cfjG32JdmA3M0o0R4c2+uUypFKLx/FgCyI8rv2CcywfKZDlFPmYSEhUI/8U
SNILIpGyhZxDD7HZo0IwQDAPBgNVHRMBAf8EBTADAQH/MA4GA1UdDwEB/wQEAwIB
BjAdBgNVHQ4EFgQUppL9x4e+/mBLKee/lbAY5sMyE1YwCgYIKoZIzj0EAwIDSAAw
RQIhANHmIwOkIiRT1yroQi/ekN7btnIWyveWlT457sTYr+ajAiA5RNFqWVqfdhQA
Wmc+O1VsCmim4xxBGTbEYpIdICam5w==
-----END CERTIFICATE-----
2025-08-18 18:54:14.875008 [INFO] evse_manager_1:  :: All errors cleared
2025-08-18 18:54:14.899952 [INFO] evse_security:E  :: Requesting certificate location: [CSMS] location:"/ext/dist/etc/everest/certs/ca/csms/CSMS_ROOT_CA.pem"
2025-08-18 18:54:14.937804 [INFO] ocpp:OCPP201     :: Loading CA csms bundle to verify server certificate: /ext/dist/etc/everest/certs/ca/csms/CSMS_ROOT_CA.pem
2025-08-18 18:54:14.957779 [INFO] ocpp:OCPP201     :: LWS connect with info port: [443] address: [host.docker.internal] path: [/ws/cp001] protocol: [ocpp2.1, ocpp2.0.1] security profile: [3]
2025-08-18 18:54:15.045743 [INFO] ocpp:OCPP201     :: OCPP client successfully connected to server with version: ocpp2.0.1
2025-08-18 18:54:15.049489 [INFO] api:API          :: Received OCPP connection status callback with is_connected: true
2025-08-18 18:54:15.071309 [INFO] ocpp:OCPP201     :: Received BootNotificationResponse: {
    "currentTime": "2025-08-18T18:54:15.000Z",
    "interval": 300,
    "status": "Accepted"
}
with messageId: cf967b43-bf5c-4746-9445-f61a1389723e
2025-08-18 18:54:15.196061 [INFO] evse_security:E  :: Requesting leaf certificate info: V2G
2025-08-18 18:54:15.204579 [INFO] evse_security:E  :: Found valid leaf: ["/ext/dist/etc/everest/certs/client/cso/CPO_CERT_CHAIN.pem"]
2025-08-18 18:54:15.205128 [INFO] evse_security:E  :: Found valid leaf: ["/ext/dist/etc/everest/certs/client/cso/SECC_LEAF.pem"]
2025-08-18 18:54:15.206232 [INFO] evse_security:E  :: SHANKARI: Considering chain ""
2025-08-18 18:54:15.206244 [INFO] evse_security:E  :: SHANKARI: Considering cert host.docker.internal
2025-08-18 18:54:15.206338 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA2
2025-08-18 18:54:15.206438 [INFO] evse_security:E  :: SHANKARI: Considering cert CPOSubCA1
2025-08-18 18:54:15.206687 [INFO] evse_security:E  :: SHANKARI: Considering chain "/ext/dist/etc/everest/certs/ca/v2g/V2G_ROOT_CA.pem"
2025-08-18 18:54:15.206698 [INFO] evse_security:E  :: SHANKARI: Considering cert V2GRootCA
2025-08-18 18:54:15.211184 [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
2025-08-18 18:54:15.211276 [WARN] evse_security:E evse_security::OCSPRequestDataList evse_security::generate_ocsp_request_data_internal(const std::map<CaCertificateType, std::filesystem::__cxx11::path>&, const std::set<CaCertificateType>&, const std::vector<X509Wrapper>&) :: When generating an OCSP request, could not find responder URL for certificate: host.docker.internal
2025-08-18 18:54:15.242110 [INFO] ocpp:OCPP201     :: libocpp: Updating OCSP cache on 2 certificates
2025-08-18 18:54:15.618762 [WARN] ocpp:OCPP201    void ocpp::v2::OcspUpdater::updater_thread_loop() :: libocpp: OCSP status update failed: CSMS rejected certificate status update: (No status info provided), will retry.
2025-08-18 18:54:32.189837 [INFO] evse_manager_1:  :: SYS  Session logging started.
2025-08-18 18:54:32.190347 [INFO] evse_manager_1:  :: EVSE IEC Session Started: EVConnected
2025-08-18 18:54:34.360306 [INFO] evse_manager_1:  :: EVSE ISO SLAC MATCHED
2025-08-18 18:54:34.361559 [INFO] evse_manager_1:  :: EVSE ISO D-LINK_READY (true)
2025-08-18 18:54:34.409531 [INFO] iso15118_car     :: _handler_start_charging() args: {'DepartureTime': 86400.0, 'EAmount': 0.0, 'EnergyTransferMode': 'AC_single_phase_core'}
2025-08-18 18:54:35.843631 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: EXI Codec version: 1.55
2025-08-18 18:54:35.844021 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Starting 15118 version: 0.28.3
2025-08-18 18:54:35.844202 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Communication session handler started
2025-08-18 18:54:35.844750 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Remaining ESDP requests: 5
2025-08-18 18:54:35.949983 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: ASN1 file written to esdp_encoded.dat at /workspace
2025-08-18 18:54:35.954254 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: File is of size 132
2025-08-18 18:54:35.954459 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: ESDP packet size is 132 bytes
2025-08-18 18:54:35.954638 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Sending ESDPRequest: [esdp_version: 256, max_payload_size: 256, esdp_extensions: b'0\x81\x810\x7f0\x08\x02\x01\x01\x04\x03\n\x01\x010\n\x02\x01\x02\x04\x050\x03\n\x01\x030\x1d\x02\x01\x04\x04\x180\x160\x14\n\x01\x010\x0f0\r\n\x01\x020\x03\n\x01\x000\x03\n\x01\x000\x0f\x02\x01\x06\x04\n0\x08\x02\x02\x03\x98\x02\x02\x00\xfa0\n\x02\x01\x07\x04\x050\x03\x02\x01P0+\x02\x01\x08\x04&0$\x04\x11\x00\x11"3DUfw\x88\x99\xaa\xbb\xcc\xdd\xee\xff\x00\x0c\x0fv2.5.6_20241031']

2025-08-18 18:54:35.955322 [INFO] iso15118_charge  :: Recieved ESDP packet. Reported payload length is 136 bytes while received payload length is 136 bytes

2025-08-18 18:54:35.955477 [INFO] iso15118_charge  :: EVCC's reported ESDP Version: 256

2025-08-18 18:54:35.955486 [INFO] iso15118_charge  :: EVCC's reported Max V2GTP Payload size: 256 bytes

2025-08-18 18:54:35.955625 [INFO] iso15118_charge  :: Successully decoded ESDPReq payload

2025-08-18 18:54:35.955663 [INFO] iso15118_charge  :: Successfully decoded Charging Interface extension

2025-08-18 18:54:35.955668 [INFO] iso15118_charge  ::      Charging Interface: CCS1

2025-08-18 18:54:35.955673 [INFO] iso15118_charge  :: Successfully decoded Basic Signaling Protocol extension

2025-08-18 18:54:35.955679 [INFO] iso15118_charge  ::      Basic Signaling Protocol[1]: IEC 61851-23 Ed-02

2025-08-18 18:54:35.955693 [INFO] iso15118_charge  :: Successfully decoded HLC Extension

2025-08-18 18:54:35.955706 [INFO] iso15118_charge  :: Successfully decoded DC Charging Limits extension

2025-08-18 18:54:35.955710 [INFO] iso15118_charge  ::      Maximum Voltage: 920 [V]

2025-08-18 18:54:35.955713 [INFO] iso15118_charge  ::      Minimum Voltage: 250 [V]

2025-08-18 18:54:35.955717 [INFO] iso15118_charge  :: Successfully decoded Conductive Charging Interface Limitations extension

2025-08-18 18:54:35.955720 [INFO] iso15118_charge  ::      Maximum Contactor Temp: 80 [C]

2025-08-18 18:54:35.955724 [INFO] iso15118_charge  :: Successfully decoded EV Characteristics extension

2025-08-18 18:54:35.955729 [INFO] iso15118_charge  ::      VIN (in Hex format): 00:11:22:33:44:55:66:77:88:99:AA:BB:CC:DD:EE:FF:00

2025-08-18 18:54:35.955732 [INFO] iso15118_charge  ::      EVCC Software Version: v2.5.6_20241031

2025-08-18 18:54:35.955743 [INFO] iso15118_charge  :: Successfully decoded Standardized Extensions in ESDPReq payload

2025-08-18 18:54:35.955747 [INFO] iso15118_charge  :: External ESDP Extensions NOT present in the received ESDPReq message

2025-08-18 18:54:35.955750 [INFO] iso15118_charge  :: Received ESDP packet from [fe80::d050:41ff:fe6e:d571]:49187 with security 0x10 and protocol 0x00

2025-08-18 18:54:35.955754 [INFO] iso15118_charge  :: ESDP requested NO-TLS, announcing NO-TLS

2025-08-18 18:54:35.956173 [INFO] iso15118_charge  :: ESDP extensions data encoded: 369 bytes

2025-08-18 18:54:35.956240 [INFO] iso15118_charge  :: sendto([fe80::d050:41ff:fe6e:d571]:49187) succeeded for ESDP

2025-08-18 18:54:35.956246 [INFO] iso15118_charge  :: Size of ESDPRes message sent: 381 bytes
2025-08-18 18:54:35.956493 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Received datagram from UDP server at address ('fe80::d050:41ff:fe6e:d571', 15200, 0, 11)
2025-08-18 18:54:35.956690 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Sending ESDP Request
2025-08-18 18:54:35.963236 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: SECC requested Security = tls12-server
2025-08-18 18:54:35.963517 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: ESDPResponse received: [ ESDP Version: 256, Max Payload Size: 4096, IP address: fe80::d050:41ff:fe6e:d571, Port: 61341 , Security: TLS , Transport: TCP ]
2025-08-18 18:54:35.963576 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Starting TLS client, trying to connect to fe80::d050:41ff:fe6e:d571 at port 61341 ...

2025-08-18 18:54:35.980328 [INFO] iso15118_charge  :: Recieved ESDP packet. Reported payload length is 136 bytes while received payload length is 136 bytes

2025-08-18 18:54:35.980387 [INFO] iso15118_charge  :: EVCC's reported ESDP Version: 256

2025-08-18 18:54:35.980392 [INFO] iso15118_charge  :: EVCC's reported Max V2GTP Payload size: 256 bytes

2025-08-18 18:54:35.980404 [INFO] iso15118_charge  :: Successully decoded ESDPReq payload

2025-08-18 18:54:35.980410 [INFO] iso15118_charge  :: Successfully decoded Charging Interface extension

2025-08-18 18:54:35.980414 [INFO] iso15118_charge  ::      Charging Interface: CCS1

2025-08-18 18:54:35.980419 [INFO] iso15118_charge  :: Successfully decoded Basic Signaling Protocol extension

2025-08-18 18:54:35.980423 [INFO] iso15118_charge  ::      Basic Signaling Protocol[1]: IEC 61851-23 Ed-02

2025-08-18 18:54:35.980428 [INFO] iso15118_charge  :: Successfully decoded HLC Extension

2025-08-18 18:54:35.980433 [INFO] iso15118_charge  :: Successfully decoded DC Charging Limits extension

2025-08-18 18:54:35.980437 [INFO] iso15118_charge  ::      Maximum Voltage: 920 [V]

2025-08-18 18:54:35.980440 [INFO] iso15118_charge  ::      Minimum Voltage: 250 [V]

2025-08-18 18:54:35.980444 [INFO] iso15118_charge  :: Successfully decoded Conductive Charging Interface Limitations extension

2025-08-18 18:54:35.980447 [INFO] iso15118_charge  ::      Maximum Contactor Temp: 80 [C]

2025-08-18 18:54:35.980451 [INFO] iso15118_charge  :: Successfully decoded EV Characteristics extension

2025-08-18 18:54:35.980457 [INFO] iso15118_charge  ::      VIN (in Hex format): 00:11:22:33:44:55:66:77:88:99:AA:BB:CC:DD:EE:FF:00

2025-08-18 18:54:35.980461 [INFO] iso15118_charge  ::      EVCC Software Version: v2.5.6_20241031

2025-08-18 18:54:35.980464 [INFO] iso15118_charge  :: Successfully decoded Standardized Extensions in ESDPReq payload

2025-08-18 18:54:35.980468 [INFO] iso15118_charge  :: External ESDP Extensions NOT present in the received ESDPReq message

2025-08-18 18:54:35.980472 [INFO] iso15118_charge  :: Received ESDP packet from [fe80::d050:41ff:fe6e:d571]:49187 with security 0x10 and protocol 0x00

2025-08-18 18:54:35.980475 [INFO] iso15118_charge  :: ESDP requested NO-TLS, announcing NO-TLS

2025-08-18 18:54:35.980526 [INFO] iso15118_charge  :: ESDP extensions data encoded: 369 bytes

2025-08-18 18:54:35.980561 [INFO] iso15118_charge  :: sendto([fe80::d050:41ff:fe6e:d571]:49187) succeeded for ESDP

2025-08-18 18:54:35.980565 [INFO] iso15118_charge  :: Size of ESDPRes message sent: 381 bytes
2025-08-18 18:54:35.988657 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Received datagram from UDP server at address ('fe80::d050:41ff:fe6e:d571', 15200, 0, 11)

2025-08-18 18:54:35.990990 [INFO] iso15118_charge  :: Incoming connection on eth0 from [a00:8afc:0:0:fe80::]:35580

2025-08-18 18:54:35.991382 [INFO] iso15118_charge  :: Started new TCP connection thread

2025-08-18 18:54:35.991487 [ERRO] iso15118_charge void dlog_func(dloglevel_t, const char*, int, const char*, const char*, ...) :: Invalid v2gtp header

2025-08-18 18:54:35.991509 [ERRO] iso15118_charge void dlog_func(dloglevel_t, const char*, int, const char*, const char*, ...) :: v2g_incoming_v2gtp() failed

2025-08-18 18:54:35.991520 [INFO] iso15118_charge  :: v2g_handle_connection exited with -1

2025-08-18 18:54:35.991534 [INFO] iso15118_charge  :: Closing TCP connection
2025-08-18 18:54:38.002000 [ERRO] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: ConnectionResetError when trying to connect to host fe80::d050:41ff:fe6e:d571 and port 61341
Traceback (most recent call last):
  File "/ext/dist/libexec/everest/modules/PyEvJosev/../../3rd_party/josev/iso15118/evcc/comm_session_handler.py", line 466, in start_comm_session
    self.tcp_client = await TCPClient.create(
                      ^^^^^^^^^^^^^^^^^^^^^^^
  File "/ext/dist/libexec/everest/modules/PyEvJosev/../../3rd_party/josev/iso15118/evcc/transport/tcp_client.py", line 62, in create
    raise exc
  File "/ext/dist/libexec/everest/modules/PyEvJosev/../../3rd_party/josev/iso15118/evcc/transport/tcp_client.py", line 53, in create
    self.reader, self.writer = await asyncio.open_connection(
                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/asyncio/streams.py", line 48, in open_connection
    transport, _ = await loop.create_connection(
                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/asyncio/base_events.py", line 1112, in create_connection
    transport, protocol = await self._create_connection_transport(
                          ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/asyncio/base_events.py", line 1145, in _create_connection_transport
    await waiter
  File "/usr/lib/python3.11/asyncio/sslproto.py", line 574, in _on_handshake_complete
    raise handshake_exc
ConnectionResetError

2025-08-18 18:54:40.992029 [INFO] iso15118_charge  :: TCP connection closed gracefully
2025-08-18 18:54:44.456072 [INFO] ocpp:OCPP201     :: About to publish composite charging schedules:
2025-08-18 18:54:44.456240 [INFO] ocpp:OCPP201     :: {
    "chargingRateUnit": "A",
    "chargingSchedulePeriod": [
        {
            "limit": 16.0,
            "startPeriod": 0
        }
    ],
    "duration": 600,
    "evseId": 0,
    "scheduleStart": "2025-08-18T18:54:44.000Z"
}
2025-08-18 18:54:44.456384 [INFO] ocpp:OCPP201     :: {
    "chargingRateUnit": "A",
    "chargingSchedulePeriod": [
        {
            "limit": 16.0,
            "startPeriod": 0
        }
    ],
    "duration": 600,
    "evseId": 1,
    "scheduleStart": "2025-08-18T18:54:44.000Z"
}
2025-08-18 18:54:44.457929 [INFO] api:API          :: Received OCPP charging schedule update: {"schedules":[{"charging_rate_unit":"A","charging_schedule_period":[{"limit":16.0,"start_period":0}],"duration":600,"evse":0,"start_schedule":"2025-08-18T18:54:44.000Z"},{"charging_rate_unit":"A","charging_schedule_period":[{"limit":16.0,"start_period":0}],"duration":600,"evse":1,"start_schedule":"2025-08-18T18:54:44.000Z"}]}

[catarial]

I ran the demo with esdp patches disabled and it looks like I got a successful TLS connection for SDP.

2025-08-18 19:34:04.986314 [INFO] iso15118_charge  :: SDP requested TLS, announcing TLS

2025-08-18 19:34:04.986419 [INFO] iso15118_charge  :: sendto([fe80::44a1:13ff:fe34:eede]:41867) succeeded

2025-08-18 19:34:04.986459 [INFO] iso15118_charge  :: Received packet from [fe80::44a1:13ff:fe34:eede]:41867 with security 0x00 and protocol 0x00

2025-08-18 19:34:04.986477 [INFO] iso15118_charge  :: SDP requested TLS, announcing TLS

2025-08-18 19:34:04.986500 [INFO] iso15118_charge  :: sendto([fe80::44a1:13ff:fe34:eede]:41867) succeeded
2025-08-18 19:34:04.986697 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Received datagram from UDP server at address ('fe80::44a1:13ff:fe34:eede', 15118, 0, 11)
2025-08-18 19:34:04.987122 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Received datagram from UDP server at address ('fe80::44a1:13ff:fe34:eede', 15118, 0, 11)
2025-08-18 19:34:04.988048 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: SDPResponse received: [ IP address: fe80::44a1:13ff:fe34:eede, Port: 64109 , Security: TLS , Transport: TCP ]
2025-08-18 19:34:04.988299 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Starting TLS client, trying to connect to fe80::44a1:13ff:fe34:eede at port 64109 ...

2025-08-18 19:34:05.016828 [INFO] iso15118_charge  :: Incoming TLS connection
2025-08-18 19:34:05.020384 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: TCP client connected
2025-08-18 19:34:05.053155 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Starting a new communication session
2025-08-18 19:34:05.053273 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Entered state SupportedAppProtocol
2025-08-18 19:34:05.053839 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Message to encode (ns=Namespace.SAP): {"supportedAppProtocolReq": {"AppProtocol": [{"ProtocolNamespace": "urn:iso:15118:2:2013:MsgDef", "VersionNumberMajor": 2, "VersionNumberMinor": 0, "SchemaID": 1, "Priority": 1}]}}
2025-08-18 19:34:05.086050 [DEBG] iso15118_car    pybind11_init_everestpy(pybind11::module_&)::<lambda(const std::string&)> :: Sent supportedAppProtocolReq

[catarial]

For ESDP there seems to be something wrong with the v2g 'header' that the simulator is sending. If I can modify the code to print that header for both cases that could be useful

[shankari]

@catarial maybe you can flag this to @ed-watt and the NREL team can fix it so that we can proceed with automation.