10:36:14,957 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word clr with class [org.springframework.boot.logging.logback.ColorConverter]
10:36:14,957 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word wEx with class [org.springframework.boot.logging.logback.ExtendedWhitespaceThrowableProxyConverter]
10:36:14,957 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - <level> element is deprecated. Near [level] on line 25
10:36:14,957 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within <logger> or <root> elements instead.
10:36:14,958 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE]
10:36:14,958 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
10:36:14,958 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
10:36:14,959 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [TEST-FILE]
10:36:14,959 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
10:36:14,959 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
10:36:14,959 |-INFO in ch.qos.logback.core.FileAppender[TEST-FILE] - File property is set to [LOG_FILE_IS_UNDEFINED]
10:36:14,959 |-INFO in ch.qos.logback.classic.jul.LevelChangePropagator@27f0a71d - Propagating DEBUG level on Logger[com.yablochkov] onto the JUL framework
10:36:14,959 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - com.yablochkov level set to DEBUG
10:36:14,959 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to WARN
10:36:14,959 |-INFO in ch.qos.logback.classic.jul.LevelChangePropagator@27f0a71d - Propagating WARN level on Logger[ROOT] onto the JUL framework
10:36:14,959 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [TEST-FILE] to Logger[ROOT]
10:36:14,959 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT]
10:36:14,959 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@1dc7173 - End of configuration.
10:36:14,959 |-INFO in org.springframework.boot.logging.logback.SpringBootJoranConfigurator@e77180e - Registering current configuration as safe fallback point
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v3.0.2)
10:36:14.962 [Test worker] INFO c.y.ocpp2.autotest.MeterValuesTest - Starting MeterValuesTest using Java 17.0.2 with PID 360 (started by root in /builds/yablochkov/mainboard/autotests/autotests2.0)
10:36:14.962 [Test worker] DEBUG c.y.ocpp2.autotest.MeterValuesTest - Running with Spring Boot, Spring
10:36:14.962 [Test worker] INFO c.y.ocpp2.autotest.MeterValuesTest - The following 1 profile is active: "test"
10:36:15.089 [Test worker] INFO c.y.ocpp2.autotest.MeterValuesTest - Started MeterValuesTest in 0.147 seconds (process running for 897.185)
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-30T10:36:49.300885015Z"}],"timestamp":"2026-04-30T10:36:49.300910716Z","triggerReason":"CablePluggedIn","seqNo":0,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1}}
TransactionEvent for evseId= 2{"eventType":"Updated","timestamp":"2026-04-30T10:36:49.325511778Z","triggerReason":"Authorized","seqNo":1,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:54.292220359Z"}],"timestamp":"2026-04-30T10:36:54.292246240Z","triggerReason":"MeterValuePeriodic","seqNo":2,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:59.300862278Z"}],"timestamp":"2026-04-30T10:36:59.300890216Z","triggerReason":"MeterValuePeriodic","seqNo":3,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:49.300885015Z"}],"timestamp":"2026-04-30T10:36:49.300910716Z","triggerReason":"CablePluggedIn","seqNo":0,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:49.300885015Z"}],"timestamp":"2026-04-30T10:36:49.300910716Z","triggerReason":"CablePluggedIn","seqNo":0,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1}}
TransactionEvent for evseId= 2{"eventType":"Updated","timestamp":"2026-04-30T10:36:49.325511778Z","triggerReason":"Authorized","seqNo":1,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:54.292220359Z"}],"timestamp":"2026-04-30T10:36:54.292246240Z","triggerReason":"MeterValuePeriodic","seqNo":2,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:59.300862278Z"}],"timestamp":"2026-04-30T10:36:59.300890216Z","triggerReason":"MeterValuePeriodic","seqNo":3,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
{"eventType":"Updated","timestamp":"2026-04-30T10:36:49.325511778Z","triggerReason":"Authorized","seqNo":1,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:54.292220359Z"}],"timestamp":"2026-04-30T10:36:54.292246240Z","triggerReason":"MeterValuePeriodic","seqNo":2,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:59.300862278Z"}],"timestamp":"2026-04-30T10:36:59.300890216Z","triggerReason":"MeterValuePeriodic","seqNo":3,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:49.300885015Z"}],"timestamp":"2026-04-30T10:36:49.300910716Z","triggerReason":"CablePluggedIn","seqNo":0,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1}}
TransactionEvent for evseId= 2{"eventType":"Updated","timestamp":"2026-04-30T10:36:49.325511778Z","triggerReason":"Authorized","seqNo":1,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:54.292220359Z"}],"timestamp":"2026-04-30T10:36:54.292246240Z","triggerReason":"MeterValuePeriodic","seqNo":2,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:59.300862278Z"}],"timestamp":"2026-04-30T10:36:59.300890216Z","triggerReason":"MeterValuePeriodic","seqNo":3,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:37:04.311677456Z"}],"timestamp":"2026-04-30T10:37:04.311704142Z","triggerReason":"MeterValuePeriodic","seqNo":4,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","chargingState":"EVConnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
TransactionEvent for evseId= 2{"eventType":"Updated","timestamp":"2026-04-30T10:37:08.787961656Z","triggerReason":"ChargingStateChanged","seqNo":5,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:49.280890614Z"},{"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-30T10:37:08.821550920Z"}],"timestamp":"2026-04-30T10:37:08.821575888Z","triggerReason":"EVCommunicationLost","seqNo":6,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","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-30T10:36:49.280890614Z"},{"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-30T10:37:08.821550920Z"}],"timestamp":"2026-04-30T10:37:08.821575888Z","triggerReason":"EVCommunicationLost","seqNo":6,"transactionInfo":{"transactionId":"c514f3d5-402d-4572-a294-833dbc5c17fe","chargingState":"Idle","stoppedReason":"EVDisconnected"},"evse":{"id":2,"connectorId":1},"idToken":{"idToken":"","type":"NoAuthorization"}}
Ended meter values
Value = 0.0
Context = Transaction.Begin
Measurand = Current.Offered
unitOfMeasure = A
Value = 0.0
Context = Transaction.Begin
Measurand = Power.Offered
unitOfMeasure = W
Value = 0.0
Context = Transaction.End
Measurand = Current.Offered
unitOfMeasure = A
Value = 0.0
Context = Transaction.End
Measurand = Power.Offered
unitOfMeasure = W