13:55:22.656 [Test worker] DEBUG org.springframework.test.context.support.AbstractContextLoader - Could not detect default resource locations for test class [com.yablochkov.autotest.util.AbstractIntegrationTest]: no resource found for suffixes {-context.xml, Context.groovy}.
13:55:22.684 [Test worker] DEBUG org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Using ContextCustomizers for test class [ColdBootTest]: [DisableObservabilityContextCustomizer, PropertyMappingContextCustomizer, Customizer, ExcludeFilterContextCustomizer, DuplicateJsonObjectContextCustomizer, MockitoContextCustomizer, TestRestTemplateContextCustomizer]
13:55:22.834 [Test worker] DEBUG org.springframework.test.context.util.TestContextSpringFactoriesUtils - Skipping candidate TestExecutionListener [org.springframework.test.context.transaction.TransactionalTestExecutionListener] due to a missing dependency. Specify custom TestExecutionListener classes or make the default TestExecutionListener classes and their required dependencies available. Offending class: [org/springframework/transaction/interceptor/TransactionAttributeSource]
13:55:22.837 [Test worker] DEBUG org.springframework.test.context.util.TestContextSpringFactoriesUtils - Skipping candidate TestExecutionListener [org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener] due to a missing dependency. Specify custom TestExecutionListener classes or make the default TestExecutionListener classes and their required dependencies available. Offending class: [org/springframework/transaction/interceptor/TransactionAttribute]
13:55:22.839 [Test worker] DEBUG org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Using TestExecutionListeners for test class [ColdBootTest]: [ServletTestExecutionListener, DirtiesContextBeforeModesTestExecutionListener, ApplicationEventsTestExecutionListener, MockitoTestExecutionListener, DependencyInjectionTestExecutionListener, DirtiesContextTestExecutionListener, EventPublishingTestExecutionListener, RestDocsTestExecutionListener, MockRestServiceServerResetTestExecutionListener, MockMvcPrintOnlyOnFailureTestExecutionListener, WebDriverTestExecutionListener, MockWebServiceServerTestExecutionListener, ResetMocksTestExecutionListener]
13:55:22.839 [Test worker] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - Before test class: class [ColdBootTest], class annotated with @DirtiesContext [true] with mode [AFTER_CLASS]
13:55:21,757 |-INFO in ch.qos.logback.classic.LoggerContext[default] - This is logback-classic version ?
13:55:21,771 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
13:55:21,771 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.xml]
13:55:21,774 |-INFO in ch.qos.logback.classic.BasicConfigurator@22ff1372 - Setting up default configuration.
13:55:23,129 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word clr with class [org.springframework.boot.logging.logback.ColorConverter]
13:55:23,129 |-INFO in ch.qos.logback.core.joran.action.ConversionRuleAction - registering conversion word wEx with class [org.springframework.boot.logging.logback.ExtendedWhitespaceThrowableProxyConverter]
13:55:23,132 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - <level> element is deprecated. Near [level] on line 25
13:55:23,132 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within <logger> or <root> elements instead.
13:55:23,132 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - <level> element is deprecated. Near [level] on line 30
13:55:23,132 |-WARN in ch.qos.logback.classic.joran.action.LevelAction - Please use "level" attribute within <logger> or <root> elements instead.
13:55:23,170 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [CONSOLE]
13:55:23,170 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
13:55:23,174 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
13:55:23,193 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - Processing appender named [TEST-FILE]
13:55:23,193 |-INFO in ch.qos.logback.core.model.processor.AppenderModelHandler - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
13:55:23,196 |-INFO in ch.qos.logback.core.model.processor.ImplicitModelHandler - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
13:55:23,196 |-INFO in ch.qos.logback.core.FileAppender[TEST-FILE] - File property is set to [LOG_FILE_IS_UNDEFINED]
13:55:23,198 |-INFO in ch.qos.logback.classic.jul.LevelChangePropagator@356341c9 - Propagating DEBUG level on Logger[com.yablochkov] onto the JUL framework
13:55:23,198 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - com.yablochkov level set to DEBUG
13:55:23,198 |-INFO in ch.qos.logback.classic.jul.LevelChangePropagator@356341c9 - Propagating TRACE level on Logger[org.springframework.web.servlet.mvc] onto the JUL framework
13:55:23,198 |-INFO in ch.qos.logback.classic.model.processor.LevelModelHandler - org.springframework.web.servlet.mvc level set to TRACE
13:55:23,198 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[org.springframework.web.servlet.mvc]
13:55:23,198 |-INFO in ch.qos.logback.classic.model.processor.RootLoggerModelHandler - Setting level of ROOT logger to WARN
13:55:23,198 |-INFO in ch.qos.logback.classic.jul.LevelChangePropagator@356341c9 - Propagating WARN level on Logger[ROOT] onto the JUL framework
13:55:23,198 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [TEST-FILE] to Logger[ROOT]
13:55:23,198 |-INFO in ch.qos.logback.core.model.processor.AppenderRefModelHandler - Attaching appender named [CONSOLE] to Logger[ROOT]
13:55:23,198 |-INFO in ch.qos.logback.core.model.processor.DefaultProcessor@3a70575 - End of configuration.
13:55:23,199 |-INFO in org.springframework.boot.logging.logback.SpringBootJoranConfigurator@5d96d434 - Registering current configuration as safe fallback point
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v3.0.2)
13:55:23.288 [Test worker] INFO c.y.autotest.ocpp16.ColdBootTest - Starting ColdBootTest using Java 17.0.2 with PID 365 (started by root in /builds/yablochkov/mainboard/autotests/autotests1.6)
13:55:23.289 [Test worker] DEBUG c.y.autotest.ocpp16.ColdBootTest - Running with Spring Boot, Spring
13:55:23.290 [Test worker] INFO c.y.autotest.ocpp16.ColdBootTest - The following 1 profile is active: "test"
13:55:23.541 [Test worker] TRACE o.s.w.c.s.GenericWebApplicationContext - Refreshing org.springframework.web.context.support.GenericWebApplicationContext@76134b9b, started on Thu Mar 19 13:55:23 UTC 2026
13:55:24.112 [Test worker] TRACE o.s.w.c.s.GenericWebApplicationContext - No 'messageSource' bean, using [Empty MessageSource]
13:55:24.112 [Test worker] TRACE o.s.w.c.s.GenericWebApplicationContext - No 'applicationEventMulticaster' bean, using [SimpleApplicationEventMulticaster]
13:55:24.527 [Test worker] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerAdapter - ControllerAdvice beans: 0 @ModelAttribute, 0 @InitBinder, 1 RequestBodyAdvice, 1 ResponseBodyAdvice
13:55:24.527 [Test worker] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerAdapter - ControllerAdvice beans: 0 @ModelAttribute, 0 @InitBinder, 1 RequestBodyAdvice, 1 ResponseBodyAdvice
13:55:24.598 [Test worker] TRACE o.s.w.s.m.m.a.RequestMappingHandlerMapping -
o.s.b.a.w.s.e.BasicErrorController:
{ [/error]}: error(HttpServletRequest)
{ [/error], produces [text/html]}: errorHtml(HttpServletRequest,HttpServletResponse)
13:55:24.598 [Test worker] TRACE o.s.w.s.m.m.a.RequestMappingHandlerMapping -
o.s.b.a.w.s.e.BasicErrorController:
{ [/error]}: error(HttpServletRequest)
{ [/error], produces [text/html]}: errorHtml(HttpServletRequest,HttpServletResponse)
13:55:24.600 [Test worker] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerMapping - 2 mappings in 'requestMappingHandlerMapping'
13:55:24.600 [Test worker] DEBUG o.s.w.s.m.m.a.RequestMappingHandlerMapping - 2 mappings in 'requestMappingHandlerMapping'
13:55:24.603 [Test worker] DEBUG o.s.w.s.h.BeanNameUrlHandlerMapping - Detected 0 mappings in 'beanNameHandlerMapping'
13:55:24.606 [Test worker] TRACE o.s.w.s.f.s.RouterFunctionMapping - 0 RouterFunction(s) in 'routerFunctionMapping'
13:55:24.617 [Test worker] TRACE o.s.w.s.h.SimpleUrlHandlerMapping - Mapped [/webjars/**] onto ResourceHttpRequestHandler [classpath [META-INF/resources/webjars/]]
13:55:24.617 [Test worker] TRACE o.s.w.s.h.SimpleUrlHandlerMapping - Mapped [/**] onto ResourceHttpRequestHandler [classpath [META-INF/resources/], classpath [resources/], classpath [static/], classpath [public/], ServletContext [/]]
13:55:24.617 [Test worker] DEBUG o.s.w.s.h.SimpleUrlHandlerMapping - Patterns [/webjars/**, /**] in 'resourceHandlerMapping'
13:55:24.622 [Test worker] DEBUG o.s.w.s.m.m.a.ExceptionHandlerExceptionResolver - ControllerAdvice beans: 0 @ExceptionHandler, 1 ResponseBodyAdvice
13:55:24.622 [Test worker] DEBUG o.s.w.s.m.m.a.ExceptionHandlerExceptionResolver - ControllerAdvice beans: 0 @ExceptionHandler, 1 ResponseBodyAdvice
13:55:24.693 [Test worker] TRACE o.s.w.c.s.GenericWebApplicationContext - Using LifecycleProcessor [org.springframework.context.support.DefaultLifecycleProcessor@596343e7]
13:55:24.706 [Test worker] INFO c.y.autotest.ocpp16.ColdBootTest - Started ColdBootTest in 1.823 seconds (process running for 3.557)
13:55:48.180 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP DELETE http://172.24.0.219:8080/requests
13:55:48.334 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:55:49.336 [Test worker] DEBUG c.y.o.s.c.c.CsmsStubServerClient - Try to send action request
13:55:49.338 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP POST http://172.24.0.219:8080/bootNotification
13:55:49.358 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:55:49.389 [Test worker] DEBUG o.s.web.client.RestTemplate - Writing [BootNotificationDto(status=Accepted, interval=1)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
13:55:49.451 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:55:49.876 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP DELETE http://172.24.0.219:8080/requests
13:55:49.927 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:55:51.134 [Test worker] DEBUG c.y.o.s.c.c.CsmsStubServerClient - Try to send action request
13:55:51.135 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP POST http://172.24.0.219:8080/bootNotification
13:55:51.135 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:55:51.136 [Test worker] DEBUG o.s.web.client.RestTemplate - Writing [BootNotificationDto(status=Pending, interval=1)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
13:55:51.188 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:56:46.191 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP GET http://172.24.0.219:8080/requests
13:56:46.207 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:56:46.264 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:56:46.266 [Test worker] DEBUG o.s.web.client.RestTemplate - Reading to [java.util.List<eu.chargetime.ocpp.model.Request>]
13:56:46.329 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP DELETE http://172.24.0.219:8080/requests
13:56:46.380 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:56:47.492 [Test worker] DEBUG c.y.o.s.c.c.CsmsStubServerClient - Try to send action request
13:56:47.493 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP POST http://172.24.0.219:8080/bootNotification
13:56:47.494 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:56:47.494 [Test worker] DEBUG o.s.web.client.RestTemplate - Writing [BootNotificationDto(status=Rejected, interval=1)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
13:56:47.546 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:57:42.546 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP GET http://172.24.0.219:8080/requests
13:57:42.547 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:57:42.601 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:57:42.602 [Test worker] DEBUG o.s.web.client.RestTemplate - Reading to [java.util.List<eu.chargetime.ocpp.model.Request>]
13:57:42.608 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP DELETE http://172.24.0.219:8080/requests
13:57:42.659 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:57:43.768 [Test worker] DEBUG c.y.o.s.c.c.CsmsStubServerClient - Try to send action request
13:57:43.769 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP POST http://172.24.0.219:8080/bootNotification
13:57:43.769 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:57:43.769 [Test worker] DEBUG o.s.web.client.RestTemplate - Writing [BootNotificationDto(status=Rejected, interval=1)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
13:57:43.821 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:58:38.821 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP GET http://172.24.0.219:8080/requests
13:58:38.822 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:58:38.876 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:58:38.877 [Test worker] DEBUG o.s.web.client.RestTemplate - Reading to [java.util.List<eu.chargetime.ocpp.model.Request>]
13:58:38.878 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP DELETE http://172.24.0.219:8080/requests
13:58:38.929 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:58:40.039 [Test worker] DEBUG c.y.o.s.c.c.CsmsStubServerClient - Try to send action request
13:58:40.040 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP POST http://172.24.0.219:8080/bootNotification
13:58:40.040 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:58:40.040 [Test worker] DEBUG o.s.web.client.RestTemplate - Writing [BootNotificationDto(status=Pending, interval=1)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
13:58:40.092 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:59:35.093 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP GET http://172.24.0.219:8080/requests
13:59:35.094 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:59:35.149 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:59:35.149 [Test worker] DEBUG o.s.web.client.RestTemplate - Reading to [java.util.List<eu.chargetime.ocpp.model.Request>]
13:59:35.150 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP DELETE http://172.24.0.219:8080/requests
13:59:35.202 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
13:59:36.311 [Test worker] DEBUG c.y.o.s.c.c.CsmsStubServerClient - Try to send action request
13:59:36.311 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP POST http://172.24.0.219:8080/bootNotification
13:59:36.312 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
13:59:36.312 [Test worker] DEBUG o.s.web.client.RestTemplate - Writing [BootNotificationDto(status=Accepted, interval=1)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
13:59:36.364 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
14:00:31.364 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP GET http://172.24.0.219:8080/requests
14:00:31.365 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
14:00:31.421 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
14:00:31.422 [Test worker] DEBUG o.s.web.client.RestTemplate - Reading to [java.util.List<eu.chargetime.ocpp.model.Request>]
Available 0
Available 1
Available 2
Available 3
19
14:00:31.486 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP DELETE http://172.24.0.219:8080/requests
14:00:31.537 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
14:00:32.648 [Test worker] DEBUG c.y.o.s.c.c.CsmsStubServerClient - Try to send action request
14:00:32.648 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP POST http://172.24.0.219:8080/bootNotification
14:00:32.648 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
14:00:32.648 [Test worker] DEBUG o.s.web.client.RestTemplate - Writing [BootNotificationDto(status=Pending, interval=1)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
14:00:32.701 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
14:01:43.133 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP GET http://172.24.0.219:8080/requests
14:01:43.134 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
14:01:43.237 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
14:01:43.238 [Test worker] DEBUG o.s.web.client.RestTemplate - Reading to [java.util.List<eu.chargetime.ocpp.model.Request>]
14:01:54.558 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP DELETE http://172.24.0.219:8080/requests
14:01:54.610 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
14:02:07.936 [Test worker] DEBUG c.y.o.s.c.c.CsmsStubServerClient - Try to send action request
14:02:07.937 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP POST http://172.24.0.219:8080/bootNotification
14:02:07.937 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
14:02:07.937 [Test worker] DEBUG o.s.web.client.RestTemplate - Writing [BootNotificationDto(status=Rejected, interval=1)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
14:02:07.990 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
14:03:02.991 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP GET http://172.24.0.219:8080/requests
14:03:02.991 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
14:03:03.042 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
14:03:03.043 [Test worker] DEBUG o.s.web.client.RestTemplate - Reading to [java.util.List<eu.chargetime.ocpp.model.Request>]
14:03:03.047 [Test worker] DEBUG c.y.o.s.c.c.CsmsStubServerClient - Try to send action request
14:03:03.047 [Test worker] DEBUG o.s.web.client.RestTemplate - HTTP POST http://172.24.0.219:8080/bootNotification
14:03:03.048 [Test worker] DEBUG o.s.web.client.RestTemplate - Accept=[application/json, application/xml, text/xml, application/*+json, application/*+xml]
14:03:03.048 [Test worker] DEBUG o.s.web.client.RestTemplate - Writing [BootNotificationDto(status=Accepted, interval=1)] with org.springframework.http.converter.json.MappingJackson2HttpMessageConverter
14:03:03.097 [Test worker] DEBUG o.s.web.client.RestTemplate - Response 200 OK
14:03:04.099 [Test worker] DEBUG o.s.w.c.s.GenericWebApplicationContext - Closing org.springframework.web.context.support.GenericWebApplicationContext@76134b9b, started on Thu Mar 19 13:55:23 UTC 2026