21:19:12,801 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word clr with class [org.springframework.boot.logging.logback.ColorConverter]
21:19:12,801 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word wEx with class [org.springframework.boot.logging.logback.ExtendedWhitespaceThrowableProxyConverter]
21:19:12,801 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - <level> element is deprecated. Near [level] on line 25
21:19:12,801 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within <logger> or <root> elements instead.
21:19:12,802 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE]
21:19:12,802 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
21:19:12,802 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
21:19:12,802 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [TEST-FILE]
21:19:12,802 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
21:19:12,802 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
21:19:12,803 |-INFO in ch.qos.logback.core.FileAppender[TEST-FILE] - File property is set to [LOG_FILE_IS_UNDEFINED]
21:19:12,803 |-INFO in ch.qos.logback.classic.jul.LevelChangePropagator@17a394d2 - Propagating DEBUG level on Logger[com.yablochkov] onto the JUL framework
21:19:12,803 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - com.yablochkov level set to DEBUG
21:19:12,803 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to WARN
21:19:12,803 |-INFO in ch.qos.logback.classic.jul.LevelChangePropagator@17a394d2 - Propagating WARN level on Logger[ROOT] onto the JUL framework
21:19:12,803 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [TEST-FILE] to Logger[ROOT]
21:19:12,803 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT]
21:19:12,803 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@4ac5bbc8 - End of configuration.
21:19:12,803 |-INFO in org.springframework.boot.logging.logback.SpringBootJoranConfigurator@4d437408 - Registering current configuration as safe fallback point
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v3.0.2)
21:19:12.806 [Test worker] INFO c.y.ocpp2.autotest.MeterValuesTest - Starting MeterValuesTest using Java 17.0.2 with PID 386 (started by root in /builds/yablochkov/mainboard/autotests)
21:19:12.806 [Test worker] DEBUG c.y.ocpp2.autotest.MeterValuesTest - Running with Spring Boot, Spring
21:19:12.806 [Test worker] INFO c.y.ocpp2.autotest.MeterValuesTest - The following 1 profile is active: "test"
21:19:12.937 [Test worker] INFO c.y.ocpp2.autotest.MeterValuesTest - Started MeterValuesTest in 0.152 seconds (process running for 902.01)
2 status Occupied
TransactionEvent for evseId= 2{"eventType":"Started","meterValue":[{"sampledValue":[{"value":0.0,"context":"Transaction.Begin","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.Begin","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:48.559991772Z"}],"timestamp":"2026-04-22T21:19:48.560012428Z","triggerReason":"CablePluggedIn","seqNo":0,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1}}
TransactionEvent for evseId= 2{"eventType":"Updated","timestamp":"2026-04-22T21:19:48.587249030Z","triggerReason":"Authorized","seqNo":1,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:53.563319630Z"}],"timestamp":"2026-04-22T21:19:53.563346166Z","triggerReason":"MeterValuePeriodic","seqNo":2,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:58.562508059Z"}],"timestamp":"2026-04-22T21:19:58.562534694Z","triggerReason":"MeterValuePeriodic","seqNo":3,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
{"eventType":"Started","meterValue":[{"sampledValue":[{"value":0.0,"context":"Transaction.Begin","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.Begin","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:48.559991772Z"}],"timestamp":"2026-04-22T21:19:48.560012428Z","triggerReason":"CablePluggedIn","seqNo":0,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1}}
Started meter values
Value = 0.0
Context = Transaction.Begin
Measurand = Current.Offered
unitOfMeasure = A
Value = 0.0
Context = Transaction.Begin
Measurand = Power.Offered
unitOfMeasure = W
TransactionEvent for evseId= 2{"eventType":"Started","meterValue":[{"sampledValue":[{"value":0.0,"context":"Transaction.Begin","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.Begin","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:48.559991772Z"}],"timestamp":"2026-04-22T21:19:48.560012428Z","triggerReason":"CablePluggedIn","seqNo":0,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1}}
TransactionEvent for evseId= 2{"eventType":"Updated","timestamp":"2026-04-22T21:19:48.587249030Z","triggerReason":"Authorized","seqNo":1,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:53.563319630Z"}],"timestamp":"2026-04-22T21:19:53.563346166Z","triggerReason":"MeterValuePeriodic","seqNo":2,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:58.562508059Z"}],"timestamp":"2026-04-22T21:19:58.562534694Z","triggerReason":"MeterValuePeriodic","seqNo":3,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
{"eventType":"Updated","timestamp":"2026-04-22T21:19:48.587249030Z","triggerReason":"Authorized","seqNo":1,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:53.563319630Z"}],"timestamp":"2026-04-22T21:19:53.563346166Z","triggerReason":"MeterValuePeriodic","seqNo":2,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:58.562508059Z"}],"timestamp":"2026-04-22T21:19:58.562534694Z","triggerReason":"MeterValuePeriodic","seqNo":3,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
Updated meter values
Value = 0.0
Context = Sample.Periodic
Measurand = Current.Offered
unitOfMeasure = A
Value = 0.0
Context = Sample.Periodic
Measurand = Power.Offered
unitOfMeasure = W
TransactionEvent for evseId= 2{"eventType":"Started","meterValue":[{"sampledValue":[{"value":0.0,"context":"Transaction.Begin","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.Begin","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:48.559991772Z"}],"timestamp":"2026-04-22T21:19:48.560012428Z","triggerReason":"CablePluggedIn","seqNo":0,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1}}
TransactionEvent for evseId= 2{"eventType":"Updated","timestamp":"2026-04-22T21:19:48.587249030Z","triggerReason":"Authorized","seqNo":1,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:53.563319630Z"}],"timestamp":"2026-04-22T21:19:53.563346166Z","triggerReason":"MeterValuePeriodic","seqNo":2,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:58.562508059Z"}],"timestamp":"2026-04-22T21:19:58.562534694Z","triggerReason":"MeterValuePeriodic","seqNo":3,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:20:03.577325296Z"}],"timestamp":"2026-04-22T21:20:03.577349857Z","triggerReason":"MeterValuePeriodic","seqNo":4,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","meterValue":[{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:20:08.591291676Z"}],"timestamp":"2026-04-22T21:20:08.591314198Z","triggerReason":"MeterValuePeriodic","seqNo":5,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","timestamp":"2026-04-22T21:20:08.798668561Z","triggerReason":"ChargingStateChanged","seqNo":6,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"Idle"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Ended","meterValue":[{"sampledValue":[{"value":0.0,"context":"Transaction.Begin","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.Begin","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:48.547956297Z"},{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:20:08.557599284Z"},{"sampledValue":[{"value":0.0,"context":"Transaction.Begin","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.Begin","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:20:08.566156339Z"},{"sampledValue":[{"value":0.0,"context":"Transaction.End","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.End","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:20:08.837229867Z"}],"timestamp":"2026-04-22T21:20:08.837254499Z","triggerReason":"EVCommunicationLost","seqNo":7,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"Idle","stoppedReason":"EVDisconnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
{"eventType":"Ended","meterValue":[{"sampledValue":[{"value":0.0,"context":"Transaction.Begin","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.Begin","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:19:48.547956297Z"},{"sampledValue":[{"value":0.0,"context":"Sample.Periodic","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Sample.Periodic","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:20:08.557599284Z"},{"sampledValue":[{"value":0.0,"context":"Transaction.Begin","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.Begin","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:20:08.566156339Z"},{"sampledValue":[{"value":0.0,"context":"Transaction.End","measurand":"Current.Offered","unitOfMeasure":{"unit":"A"}},{"value":0.0,"context":"Transaction.End","measurand":"Power.Offered","unitOfMeasure":{"unit":"W"}}],"timestamp":"2026-04-22T21:20:08.837229867Z"}],"timestamp":"2026-04-22T21:20:08.837254499Z","triggerReason":"EVCommunicationLost","seqNo":7,"transactionInfo":{"transactionId":"39de8bfe-2cd8-4a86-9db8-b28aaf59c4cf","chargingState":"Idle","stoppedReason":"EVDisconnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}