Master build for Spring Analytics

Build: #44 was successful

Job: Build and publish was successful

Stages & jobs

  1. Default Stage

Build log

The build generated 2,964 lines of output.The output is too long and has been truncated to the last 1,000 lines. Download or view full build log

19-Apr-2018 00:55:26 00:55:26.060 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'beanNameHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.060 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'beanNameHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.060 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.061 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'beanNameHandlerMapping' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.064 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Looking for URL mappings in application context: org.springframework.web.context.support.GenericWebApplicationContext@1a3e8e24: startup date [Thu Apr 19 00:55:25 UTC 2018]; root of context hierarchy
19-Apr-2018 00:55:26 00:55:26.064 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.internalConfigurationAnnotationProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.064 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.internalAutowiredAnnotationProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.064 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.internalRequiredAnnotationProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.064 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.internalCommonAnnotationProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.064 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.event.internalEventListenerProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.064 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.event.internalEventListenerFactory': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.064 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'counterControllerTests.Config': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.064 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.mock.mockito.MockitoPostProcessor$SpyPostProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.mock.mockito.MockitoPostProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.data.web.config.ProjectingArgumentResolverRegistrar': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'projectingArgumentResolverBeanPostProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.data.web.config.HateoasAwareSpringDataWebConfiguration': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'pageableResolver': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'sortResolver': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'pagedResourcesAssembler': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'pagedResourcesAssemblerArgumentResolver': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.data.web.config.SpringDataJacksonConfiguration': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'jacksonGeoModule': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.config.HateoasConfiguration': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'linkRelationMessageSource': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'requestMappingHandlerMapping': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcPathMatcher': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcUrlPathHelper': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.065 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcContentNegotiationManager': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'viewControllerHandlerMapping': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'beanNameHandlerMapping': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'resourceHandlerMapping': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcResourceUrlProvider': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'defaultServletHandlerMapping': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'requestMappingHandlerAdapter': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcConversionService': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcValidator': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcUriComponentsContributor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'httpRequestHandlerAdapter': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'simpleControllerHandlerAdapter': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'handlerExceptionResolver': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcViewResolver': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcHandlerMappingIntrospector': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.autoconfigure.data.redis.LettuceConnectionConfiguration': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.autoconfigure.condition.BeanTypeRegistry': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'lettuceClientResources': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.066 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'redisConnectionFactory': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'redisTemplate': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'stringRedisTemplate': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'spring.redis-org.springframework.boot.autoconfigure.data.redis.RedisProperties': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.context.properties.ConfigurationPropertiesBindingPostProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.context.properties.ConfigurationBeanFactoryMetadata': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'counterRepository': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'counterController': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'counterService': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'entityLinksPluginRegistry': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'controllerEntityLinks': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'delegatingEntityLinks': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.hal.HalLinkDiscoverer#0': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name '_halObjectMapper': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$DefaultObjectMapperCustomizer#0': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$Jackson2ModuleRegisteringBeanPostProcessor#0': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.hal.HalConfiguration#0': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.067 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name '_linkDiscovererRegistry': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.LinkDiscoverers#0': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'defaultRelProvider': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'annotationRelProvider': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'relProviderPluginRegistry': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name '_relProvider': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.context.filter.TestTypeExcludeFilter': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer$PropertyMappingCheckBeanPostProcessor': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'environment': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'systemProperties': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'systemEnvironment': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'servletContext': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'contextParameters': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'contextAttributes': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'autoConfigurationReport': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.ConfigurationClassPostProcessor.importRegistry': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.mock.mockito.MockitoBeans': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'messageSource': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.068 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'applicationEventMulticaster': no URL paths identified
19-Apr-2018 00:55:26 00:55:26.069 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'beanNameHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.069 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'resourceHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.069 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'resourceHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.069 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.069 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcContentNegotiationManager'
19-Apr-2018 00:55:26 00:55:26.069 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcUrlPathHelper'
19-Apr-2018 00:55:26 00:55:26.070 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'resourceHandlerMapping' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.071 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'resourceHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.071 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcResourceUrlProvider'
19-Apr-2018 00:55:26 00:55:26.071 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'defaultServletHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.071 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'defaultServletHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.071 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.071 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'defaultServletHandlerMapping' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.072 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'defaultServletHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.072 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'requestMappingHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.072 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'requestMappingHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.072 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.074 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcContentNegotiationManager'
19-Apr-2018 00:55:26 00:55:26.076 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean '_halObjectMapper'
19-Apr-2018 00:55:26 00:55:26.076 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '_halObjectMapper'
19-Apr-2018 00:55:26 00:55:26.077 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean '_halObjectMapper' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.080 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '_halObjectMapper'
19-Apr-2018 00:55:26 00:55:26.081 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcConversionService'
19-Apr-2018 00:55:26 00:55:26.081 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'mvcValidator'
19-Apr-2018 00:55:26 00:55:26.081 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'mvcValidator'
19-Apr-2018 00:55:26 00:55:26.081 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.082 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'mvcValidator' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.085 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'mvcValidator'
19-Apr-2018 00:55:26 00:55:26.086 [main] DEBUG org.hibernate.validator.internal.engine.resolver.TraversableResolvers - Cannot find javax.persistence.Persistence on classpath. Assuming non JPA 2 environment. All properties will per default be traversable.
19-Apr-2018 00:55:26 00:55:26.087 [main] DEBUG org.hibernate.validator.messageinterpolation.ResourceBundleMessageInterpolator - Loaded expression factory via original TCCL
19-Apr-2018 00:55:26 00:55:26.087 [main] DEBUG org.hibernate.validator.internal.engine.ConfigurationImpl - Setting custom MessageInterpolator of type org.springframework.validation.beanvalidation.LocaleContextMessageInterpolator
19-Apr-2018 00:55:26 00:55:26.087 [main] DEBUG org.hibernate.validator.internal.engine.ConfigurationImpl - Setting custom ConstraintValidatorFactory of type org.springframework.validation.beanvalidation.SpringConstraintValidatorFactory
19-Apr-2018 00:55:26 00:55:26.087 [main] DEBUG org.hibernate.validator.internal.engine.ConfigurationImpl - Setting custom ParameterNameProvider of type org.springframework.validation.beanvalidation.LocalValidatorFactoryBean$1
19-Apr-2018 00:55:26 00:55:26.087 [main] DEBUG org.hibernate.validator.internal.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
19-Apr-2018 00:55:26 00:55:26.087 [main] DEBUG org.hibernate.validator.internal.xml.ResourceLoaderHelper - Trying to load META-INF/validation.xml via user class loader
19-Apr-2018 00:55:26 00:55:26.087 [main] DEBUG org.hibernate.validator.internal.xml.ResourceLoaderHelper - Trying to load META-INF/validation.xml via TCCL
19-Apr-2018 00:55:26 00:55:26.088 [main] DEBUG org.hibernate.validator.internal.xml.ResourceLoaderHelper - Trying to load META-INF/validation.xml via Hibernate Validator's class loader
19-Apr-2018 00:55:26 00:55:26.088 [main] DEBUG org.hibernate.validator.internal.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
19-Apr-2018 00:55:26 00:55:26.091 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.springframework.validation.beanvalidation.LocaleContextMessageInterpolator as ValidatorFactory-scoped message interpolator.
19-Apr-2018 00:55:26 00:55:26.091 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.hibernate.validator.internal.engine.resolver.TraverseAllTraversableResolver as ValidatorFactory-scoped traversable resolver.
19-Apr-2018 00:55:26 00:55:26.091 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.hibernate.validator.internal.util.ExecutableParameterNameProvider as ValidatorFactory-scoped parameter name provider.
19-Apr-2018 00:55:26 00:55:26.092 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.hibernate.validator.internal.engine.DefaultClockProvider as ValidatorFactory-scoped clock provider.
19-Apr-2018 00:55:26 00:55:26.092 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.hibernate.validator.internal.engine.scripting.DefaultScriptEvaluatorFactory as ValidatorFactory-scoped script evaluator factory.
19-Apr-2018 00:55:26 00:55:26.093 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'mvcValidator'
19-Apr-2018 00:55:26 00:55:26.093 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'sortResolver'
19-Apr-2018 00:55:26 00:55:26.093 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'pageableResolver'
19-Apr-2018 00:55:26 00:55:26.094 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'pagedResourcesAssemblerArgumentResolver'
19-Apr-2018 00:55:26 00:55:26.095 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'requestMappingHandlerAdapter' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.099 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean '_relProvider'
19-Apr-2018 00:55:26 00:55:26.099 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '_relProvider'
19-Apr-2018 00:55:26 00:55:26.099 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '(inner bean)#52b46d52'
19-Apr-2018 00:55:26 00:55:26.102 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name '(inner bean)#52b46d52'
19-Apr-2018 00:55:26 00:55:26.102 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '(inner bean)#52b46d52'
19-Apr-2018 00:55:26 00:55:26.102 [main] DEBUG org.springframework.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is org.springframework.plugin.core.support.AbstractTypeAwareSupport$BeansOfTypeTargetSource@67022ea
19-Apr-2018 00:55:26 00:55:26.103 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean '_relProvider' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.104 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '_relProvider'
19-Apr-2018 00:55:26 00:55:26.104 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_halObjectMapper'
19-Apr-2018 00:55:26 00:55:26.104 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'linkRelationMessageSource'
19-Apr-2018 00:55:26 00:55:26.104 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.hateoas.hal.HalConfiguration#0'
19-Apr-2018 00:55:26 00:55:26.104 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.hateoas.hal.HalConfiguration#0'
19-Apr-2018 00:55:26 00:55:26.104 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.hateoas.hal.HalConfiguration#0' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.105 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.hateoas.hal.HalConfiguration#0'
19-Apr-2018 00:55:26 00:55:26.105 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'requestMappingHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.105 [main] INFO org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter - Looking for @ControllerAdvice: org.springframework.web.context.support.GenericWebApplicationContext@1a3e8e24: startup date [Thu Apr 19 00:55:25 UTC 2018]; root of context hierarchy
19-Apr-2018 00:55:26 00:55:26.107 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'requestMappingHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.107 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcConversionService'
19-Apr-2018 00:55:26 00:55:26.107 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcValidator'
19-Apr-2018 00:55:26 00:55:26.107 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'mvcUriComponentsContributor'
19-Apr-2018 00:55:26 00:55:26.107 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'mvcUriComponentsContributor'
19-Apr-2018 00:55:26 00:55:26.107 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.107 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.108 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcConversionService'
19-Apr-2018 00:55:26 00:55:26.108 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'mvcUriComponentsContributor' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.108 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'mvcUriComponentsContributor'
19-Apr-2018 00:55:26 00:55:26.109 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'httpRequestHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.109 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'httpRequestHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.109 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.109 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'httpRequestHandlerAdapter' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.110 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'httpRequestHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.110 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'simpleControllerHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.110 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'simpleControllerHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.110 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.110 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'simpleControllerHandlerAdapter' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.111 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'simpleControllerHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.111 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'handlerExceptionResolver'
19-Apr-2018 00:55:26 00:55:26.111 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'handlerExceptionResolver'
19-Apr-2018 00:55:26 00:55:26.111 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.113 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcContentNegotiationManager'
19-Apr-2018 00:55:26 00:55:26.113 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.ExceptionHandlerExceptionResolver - Looking for exception mappings: org.springframework.web.context.support.GenericWebApplicationContext@1a3e8e24: startup date [Thu Apr 19 00:55:25 UTC 2018]; root of context hierarchy
19-Apr-2018 00:55:26 00:55:26.114 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'handlerExceptionResolver' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.115 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'handlerExceptionResolver'
19-Apr-2018 00:55:26 00:55:26.115 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'mvcViewResolver'
19-Apr-2018 00:55:26 00:55:26.115 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'mvcViewResolver'
19-Apr-2018 00:55:26 00:55:26.115 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
19-Apr-2018 00:55:26 00:55:26.115 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcContentNegotiationManager'
19-Apr-2018 00:55:26 00:55:26.116 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'mvcViewResolver' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.118 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'mvcViewResolver'
19-Apr-2018 00:55:26 00:55:26.118 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'mvcViewResolver'
19-Apr-2018 00:55:26 00:55:26.118 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.autoconfigure.data.redis.LettuceConnectionConfiguration'
19-Apr-2018 00:55:26 00:55:26.118 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.autoconfigure.condition.BeanTypeRegistry'
19-Apr-2018 00:55:26 00:55:26.118 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lettuceClientResources'
19-Apr-2018 00:55:26 00:55:26.118 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'redisConnectionFactory'
19-Apr-2018 00:55:26 00:55:26.118 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
19-Apr-2018 00:55:26 00:55:26.118 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
19-Apr-2018 00:55:26 00:55:26.119 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.121 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
19-Apr-2018 00:55:26 00:55:26.121 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'redisTemplate'
19-Apr-2018 00:55:26 00:55:26.122 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'redisTemplate'
19-Apr-2018 00:55:26 00:55:26.122 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
19-Apr-2018 00:55:26 00:55:26.122 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'redisConnectionFactory'
19-Apr-2018 00:55:26 00:55:26.122 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name 'redisTemplate' via factory method to bean named 'redisConnectionFactory'
19-Apr-2018 00:55:26 00:55:26.135 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'redisTemplate' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.145 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'redisTemplate'
19-Apr-2018 00:55:26 00:55:26.149 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'redisTemplate'
19-Apr-2018 00:55:26 00:55:26.149 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'stringRedisTemplate'
19-Apr-2018 00:55:26 00:55:26.149 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'stringRedisTemplate'
19-Apr-2018 00:55:26 00:55:26.149 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
19-Apr-2018 00:55:26 00:55:26.149 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'redisConnectionFactory'
19-Apr-2018 00:55:26 00:55:26.149 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name 'stringRedisTemplate' via factory method to bean named 'redisConnectionFactory'
19-Apr-2018 00:55:26 00:55:26.150 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'stringRedisTemplate' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.154 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'stringRedisTemplate'
19-Apr-2018 00:55:26 00:55:26.155 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'stringRedisTemplate'
19-Apr-2018 00:55:26 00:55:26.155 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'spring.redis-org.springframework.boot.autoconfigure.data.redis.RedisProperties'
19-Apr-2018 00:55:26 00:55:26.155 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.context.properties.ConfigurationPropertiesBindingPostProcessor'
19-Apr-2018 00:55:26 00:55:26.155 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.context.properties.ConfigurationBeanFactoryMetadata'
19-Apr-2018 00:55:26 00:55:26.155 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'counterRepository'
19-Apr-2018 00:55:26 00:55:26.155 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'counterRepository'
19-Apr-2018 00:55:26 00:55:26.155 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterControllerTests.Config'
19-Apr-2018 00:55:26 00:55:26.163 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'counterRepository' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.165 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'counterRepository'
19-Apr-2018 00:55:26 00:55:26.165 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'counterController'
19-Apr-2018 00:55:26 00:55:26.165 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'counterController'
19-Apr-2018 00:55:26 00:55:26.165 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterControllerTests.Config'
19-Apr-2018 00:55:26 00:55:26.166 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterRepository'
19-Apr-2018 00:55:26 00:55:26.166 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name 'counterController' via factory method to bean named 'counterRepository'
19-Apr-2018 00:55:26 00:55:26.167 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'counterController' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.169 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'counterController'
19-Apr-2018 00:55:26 00:55:26.169 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'counterService'
19-Apr-2018 00:55:26 00:55:26.169 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'counterService'
19-Apr-2018 00:55:26 00:55:26.169 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterControllerTests.Config'
19-Apr-2018 00:55:26 00:55:26.169 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterRepository'
19-Apr-2018 00:55:26 00:55:26.169 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name 'counterService' via factory method to bean named 'counterRepository'
19-Apr-2018 00:55:26 00:55:26.170 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'counterService' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.172 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'counterService'
19-Apr-2018 00:55:26 00:55:26.172 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'entityLinksPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.172 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'entityLinksPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.172 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'entityLinksPluginRegistry' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.172 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'entityLinksPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.172 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'entityLinksPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.172 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'controllerEntityLinks'
19-Apr-2018 00:55:26 00:55:26.173 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'controllerEntityLinks'
19-Apr-2018 00:55:26 00:55:26.173 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'controllerEntityLinks' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.176 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '(inner bean)#72976b4'
19-Apr-2018 00:55:26 00:55:26.179 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'pageableResolver'
19-Apr-2018 00:55:26 00:55:26.179 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'sortResolver'
19-Apr-2018 00:55:26 00:55:26.179 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name '(inner bean)#72976b4' via property 'uriComponentsContributors' to bean named 'pageableResolver'
19-Apr-2018 00:55:26 00:55:26.179 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name '(inner bean)#72976b4' via property 'uriComponentsContributors' to bean named 'sortResolver'
19-Apr-2018 00:55:26 00:55:26.179 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '(inner bean)#72976b4'
19-Apr-2018 00:55:26 00:55:26.180 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'controllerEntityLinks'
19-Apr-2018 00:55:26 00:55:26.180 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'controllerEntityLinks'
19-Apr-2018 00:55:26 00:55:26.181 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'controllerEntityLinks'
19-Apr-2018 00:55:26 00:55:26.181 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'delegatingEntityLinks'
19-Apr-2018 00:55:26 00:55:26.181 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'delegatingEntityLinks'
19-Apr-2018 00:55:26 00:55:26.181 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '(inner bean)#798dad3d'
19-Apr-2018 00:55:26 00:55:26.181 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name '(inner bean)#798dad3d'
19-Apr-2018 00:55:26 00:55:26.181 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '(inner bean)#798dad3d'
19-Apr-2018 00:55:26 00:55:26.182 [main] DEBUG org.springframework.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is org.springframework.plugin.core.support.AbstractTypeAwareSupport$BeansOfTypeTargetSource@696db620
19-Apr-2018 00:55:26 00:55:26.183 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'delegatingEntityLinks' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.184 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'delegatingEntityLinks'
19-Apr-2018 00:55:26 00:55:26.184 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.hateoas.hal.HalLinkDiscoverer#0'
19-Apr-2018 00:55:26 00:55:26.184 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.hateoas.hal.HalLinkDiscoverer#0'
19-Apr-2018 00:55:26 00:55:26.184 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.hateoas.hal.HalLinkDiscoverer#0' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.185 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.hateoas.hal.HalLinkDiscoverer#0'
19-Apr-2018 00:55:26 00:55:26.185 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_halObjectMapper'
19-Apr-2018 00:55:26 00:55:26.185 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$DefaultObjectMapperCustomizer#0'
19-Apr-2018 00:55:26 00:55:26.185 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$Jackson2ModuleRegisteringBeanPostProcessor#0'
19-Apr-2018 00:55:26 00:55:26.185 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.hateoas.hal.HalConfiguration#0'
19-Apr-2018 00:55:26 00:55:26.185 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean '_linkDiscovererRegistry'
19-Apr-2018 00:55:26 00:55:26.185 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '_linkDiscovererRegistry'
19-Apr-2018 00:55:26 00:55:26.185 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean '_linkDiscovererRegistry' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.185 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name '_linkDiscovererRegistry'
19-Apr-2018 00:55:26 00:55:26.186 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '_linkDiscovererRegistry'
19-Apr-2018 00:55:26 00:55:26.186 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.hateoas.LinkDiscoverers#0'
19-Apr-2018 00:55:26 00:55:26.186 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.hateoas.LinkDiscoverers#0'
19-Apr-2018 00:55:26 00:55:26.186 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_linkDiscovererRegistry'
19-Apr-2018 00:55:26 00:55:26.186 [main] DEBUG org.springframework.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is org.springframework.plugin.core.support.AbstractTypeAwareSupport$BeansOfTypeTargetSource@2ceee4b6
19-Apr-2018 00:55:26 00:55:26.187 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.hateoas.LinkDiscoverers#0' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.187 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.hateoas.LinkDiscoverers#0'
19-Apr-2018 00:55:26 00:55:26.187 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'defaultRelProvider'
19-Apr-2018 00:55:26 00:55:26.187 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'defaultRelProvider'
19-Apr-2018 00:55:26 00:55:26.188 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'defaultRelProvider' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.188 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'defaultRelProvider'
19-Apr-2018 00:55:26 00:55:26.188 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'annotationRelProvider'
19-Apr-2018 00:55:26 00:55:26.189 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'annotationRelProvider'
19-Apr-2018 00:55:26 00:55:26.189 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'annotationRelProvider' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.190 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'annotationRelProvider'
19-Apr-2018 00:55:26 00:55:26.190 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'relProviderPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.190 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'relProviderPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.191 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'relProviderPluginRegistry' to allow for resolving potential circular references
19-Apr-2018 00:55:26 00:55:26.191 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'relProviderPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.191 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'relProviderPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.191 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_relProvider'
19-Apr-2018 00:55:26 00:55:26.192 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.context.event.internalEventListenerFactory'
19-Apr-2018 00:55:26 00:55:26.236 [main] DEBUG org.springframework.web.context.support.GenericWebApplicationContext - Unable to locate LifecycleProcessor with name 'lifecycleProcessor': using default [org.springframework.context.support.DefaultLifecycleProcessor@4bdf]
19-Apr-2018 00:55:26 00:55:26.237 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'
19-Apr-2018 00:55:26 00:55:26.237 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcResourceUrlProvider'
19-Apr-2018 00:55:26 00:55:26.237 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'entityLinksPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.238 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_linkDiscovererRegistry'
19-Apr-2018 00:55:26 00:55:26.238 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'relProviderPluginRegistry'
19-Apr-2018 00:55:26 00:55:26.238 [main] DEBUG org.springframework.web.servlet.resource.ResourceUrlProvider - Looking for resource handler mappings
19-Apr-2018 00:55:26 00:55:26.238 [main] DEBUG org.springframework.web.servlet.resource.ResourceUrlProvider - No resource handling mappings found
19-Apr-2018 00:55:26 00:55:26.238 [main] DEBUG org.springframework.core.env.PropertySourcesPropertyResolver - Could not find key 'spring.liveBeansView.mbeanDomain' in any property source
19-Apr-2018 00:55:26 00:55:26.239 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Storing ApplicationContext in cache under key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:26 00:55:26.239 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 22, missCount = 3]
19-Apr-2018 00:55:26 00:55:26.239 [main] DEBUG org.springframework.test.context.web.ServletTestExecutionListener - Setting up MockHttpServletRequest, MockHttpServletResponse, ServletWebRequest, and RequestContextHolder for test context [DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@2c63762b, testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map[[empty]]].
19-Apr-2018 00:55:26 00:55:26.239 [main] DEBUG org.springframework.test.context.support.DependencyInjectionTestExecutionListener - Performing dependency injection for test context [[DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@2c63762b, testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.populatedRequestContextHolder' -> true, 'org.springframework.test.context.web.ServletTestExecutionListener.resetRequestContextHolder' -> true]]].
19-Apr-2018 00:55:26 00:55:26.240 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:26 00:55:26.240 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 23, missCount = 3]
19-Apr-2018 00:55:26 00:55:26.242 [main] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata - Processing injected element of bean 'org.springframework.analytics.rest.controller.CounterControllerTests': AutowiredFieldElement for private org.springframework.analytics.metrics.redis.RedisMetricRepository org.springframework.analytics.rest.controller.CounterControllerTests.repository
19-Apr-2018 00:55:26 00:55:26.242 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation [interface org.springframework.beans.factory.annotation.Autowired]: java.lang.NullPointerException
19-Apr-2018 00:55:26 00:55:26.242 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterRepository'
19-Apr-2018 00:55:26 00:55:26.242 [main] DEBUG org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor - Autowiring by type from bean name 'org.springframework.analytics.rest.controller.CounterControllerTests' to bean named 'counterRepository'
19-Apr-2018 00:55:26 00:55:26.242 [main] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata - Processing injected element of bean 'org.springframework.analytics.rest.controller.CounterControllerTests': AutowiredFieldElement for private org.springframework.analytics.test.support.CounterService org.springframework.analytics.rest.controller.CounterControllerTests.counterService
19-Apr-2018 00:55:26 00:55:26.242 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation [interface org.springframework.beans.factory.annotation.Autowired]: java.lang.NullPointerException
19-Apr-2018 00:55:26 00:55:26.243 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterService'
19-Apr-2018 00:55:26 00:55:26.243 [main] DEBUG org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor - Autowiring by type from bean name 'org.springframework.analytics.rest.controller.CounterControllerTests' to bean named 'counterService'
19-Apr-2018 00:55:26 00:55:26.243 [main] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata - Processing injected element of bean 'org.springframework.analytics.rest.controller.CounterControllerTests': AutowiredFieldElement for private org.springframework.web.context.WebApplicationContext org.springframework.analytics.rest.controller.CounterControllerTests.wac
19-Apr-2018 00:55:26 00:55:26.243 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation [interface org.springframework.beans.factory.annotation.Autowired]: java.lang.NullPointerException
19-Apr-2018 00:55:26 00:55:26.243 [main] DEBUG org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor - Autowiring by type from bean name 'org.springframework.analytics.rest.controller.CounterControllerTests' to bean named 'org.springframework.web.context.support.GenericWebApplicationContext@1a3e8e24'
19-Apr-2018 00:55:26 00:55:26.246 [main] DEBUG io.lettuce.core.RedisClient - Trying to get a Redis connection for: RedisURI [host='localhost', port=6379]
19-Apr-2018 00:55:26 00:55:26.255 [main] DEBUG io.lettuce.core.resource.DefaultEventLoopGroupProvider - Adding reference to io.netty.channel.nio.NioEventLoopGroup@497d4c96, existing ref count 0
19-Apr-2018 00:55:26 00:55:26.256 [main] DEBUG io.lettuce.core.RedisClient - Resolved SocketAddress localhost:6379 using RedisURI [host='localhost', port=6379]
19-Apr-2018 00:55:26 00:55:26.256 [main] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379
19-Apr-2018 00:55:26 00:55:26.258 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x5ea89297, [id: 0xd9981a53] (inactive), chid=0x3] channelRegistered()
19-Apr-2018 00:55:26 00:55:26.258 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, chid=0x3] channelActive()
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, epid=0x3] activateEndpointAndExecuteBufferedCommands 0 command(s) buffered
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, epid=0x3] activating endpoint
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, epid=0x3] flushCommands()
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, epid=0x3] flushCommands() Flushing 0 commands
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, chid=0x3] channelActive() done
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379: Success
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@1a023217)
19-Apr-2018 00:55:26 00:55:26.259 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@1a023217)
19-Apr-2018 00:55:26 00:55:26.259 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - Before test method: context [DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@2c63762b, testMethod = testList@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.populatedRequestContextHolder' -> true, 'org.springframework.test.context.web.ServletTestExecutionListener.resetRequestContextHolder' -> true]], class annotated with @DirtiesContext [false] with mode [null], method annotated with @DirtiesContext [false] with mode [null].
19-Apr-2018 00:55:26 00:55:26.260 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:26 00:55:26.260 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 24, missCount = 3]
19-Apr-2018 00:55:26 00:55:26.260 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.test.mock.mockito.MockitoBeans'
19-Apr-2018 00:55:26 00:55:26.260 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Initializing servlet ''
19-Apr-2018 00:55:26 00:55:26.260 [main] INFO org.springframework.mock.web.MockServletContext - Initializing Spring FrameworkServlet ''
19-Apr-2018 00:55:26 00:55:26.260 [main] INFO org.springframework.test.web.servlet.TestDispatcherServlet - FrameworkServlet '': initialization started
19-Apr-2018 00:55:26 00:55:26.261 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate MultipartResolver with name 'multipartResolver': no multipart request handling provided
19-Apr-2018 00:55:26 00:55:26.261 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
19-Apr-2018 00:55:26 00:55:26.262 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
19-Apr-2018 00:55:26 00:55:26.262 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate LocaleResolver with name 'localeResolver': using default [org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver@3472947a]
19-Apr-2018 00:55:26 00:55:26.262 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
19-Apr-2018 00:55:26 00:55:26.263 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
19-Apr-2018 00:55:26 00:55:26.263 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate ThemeResolver with name 'themeResolver': using default [org.springframework.web.servlet.theme.FixedThemeResolver@69d3cf7e]
19-Apr-2018 00:55:26 00:55:26.263 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.263 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'viewControllerHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.263 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'beanNameHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.263 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'resourceHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.263 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'defaultServletHandlerMapping'
19-Apr-2018 00:55:26 00:55:26.263 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.263 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'httpRequestHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.264 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'simpleControllerHandlerAdapter'
19-Apr-2018 00:55:26 00:55:26.264 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'handlerExceptionResolver'
19-Apr-2018 00:55:26 00:55:26.264 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
19-Apr-2018 00:55:26 00:55:26.265 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
19-Apr-2018 00:55:26 00:55:26.265 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate RequestToViewNameTranslator with name 'viewNameTranslator': using default [org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator@3a3ed300]
19-Apr-2018 00:55:26 00:55:26.265 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcViewResolver'
19-Apr-2018 00:55:26 00:55:26.265 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
19-Apr-2018 00:55:26 00:55:26.266 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
19-Apr-2018 00:55:26 00:55:26.266 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate FlashMapManager with name 'flashMapManager': using default [org.springframework.web.servlet.support.SessionFlashMapManager@1984212d]
19-Apr-2018 00:55:26 00:55:26.266 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Published WebApplicationContext of servlet '' as ServletContext attribute with name [org.springframework.web.servlet.FrameworkServlet.CONTEXT.]
19-Apr-2018 00:55:26 00:55:26.266 [main] INFO org.springframework.test.web.servlet.TestDispatcherServlet - FrameworkServlet '': initialization completed in 6 ms
19-Apr-2018 00:55:26 00:55:26.266 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Servlet '' configured successfully
19-Apr-2018 00:55:26 00:55:26.271 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.271 [main] DEBUG io.lettuce.core.RedisClient - Trying to get a Redis connection for: RedisURI [host='localhost', port=6379]
19-Apr-2018 00:55:26 00:55:26.272 [main] DEBUG io.lettuce.core.resource.DefaultEventLoopGroupProvider - Adding reference to io.netty.channel.nio.NioEventLoopGroup@1b410308, existing ref count 0
19-Apr-2018 00:55:26 00:55:26.272 [main] DEBUG io.lettuce.core.RedisClient - Resolved SocketAddress localhost:6379 using RedisURI [host='localhost', port=6379]
19-Apr-2018 00:55:26 00:55:26.272 [main] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379
19-Apr-2018 00:55:26 00:55:26.274 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, [id: 0x5e6ea5ef] (inactive), chid=0x4] channelRegistered()
19-Apr-2018 00:55:26 00:55:26.275 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] channelActive()
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] activateEndpointAndExecuteBufferedCommands 0 command(s) buffered
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] activating endpoint
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] flushCommands()
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] flushCommands() Flushing 0 commands
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] channelActive() done
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379: Success
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@1ecaad4e)
19-Apr-2018 00:55:26 00:55:26.276 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@1ecaad4e)
19-Apr-2018 00:55:26 00:55:26.291 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.291 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.293 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.293 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.298 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.358 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.358 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.358 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.361 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=0.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.373 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.373 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.373 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.374 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.374 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.374 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.374 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.375 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.375 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.375 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.375 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=1.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.375 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.377 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.380 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.380 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.380 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.380 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.381 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.381 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.381 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.381 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.381 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=SET, output=StatusOutput [output=OK, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.382 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.382 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.383 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.383 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.383 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.383 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.383 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.384 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.384 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.384 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.384 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=0.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.384 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.384 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.385 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.385 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.385 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.385 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.385 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.386 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.386 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.386 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.386 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=1.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.386 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.386 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.386 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.387 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.387 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.387 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.387 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.388 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.388 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.388 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.388 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=SET, output=StatusOutput [output=OK, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.388 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.389 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - DispatcherServlet with name '' processing GET request for [/metrics/counters]
19-Apr-2018 00:55:26 00:55:26.389 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Looking up handler method for path /metrics/counters
19-Apr-2018 00:55:26 00:55:26.389 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Returning handler method [public org.springframework.hateoas.PagedResources<? extends org.springframework.analytics.rest.domain.MetricResource> org.springframework.analytics.rest.controller.CounterController.list(org.springframework.data.domain.Pageable,org.springframework.data.web.PagedResourcesAssembler<org.springframework.analytics.rest.domain.Metric<java.lang.Double>>,boolean)]
19-Apr-2018 00:55:26 00:55:26.389 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterController'
19-Apr-2018 00:55:26 00:55:26.389 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Last-Modified value for [/metrics/counters] is: -1
19-Apr-2018 00:55:26 00:55:26.393 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.394 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.394 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.395 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.395 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.395 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.396 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 70 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.396 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.396 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.397 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZRANGE, output=ValueListOutput [output=[[B@9860df4, [B@73c07db6], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.397 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.398 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.399 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.399 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.399 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.399 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.399 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.400 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 44 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.400 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.400 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.400 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=MGET, output=KeyValueListOutput [output=[KeyValue[[B@71d578d0, [B@40c2873c], KeyValue[[B@7912db7d, [B@94f2424]], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.401 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.401 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.401 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.402 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.402 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.402 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.402 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.403 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.403 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.403 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.403 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=1.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.403 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.403 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.404 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.404 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.404 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.404 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.409 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.409 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.409 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.409 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.409 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=1.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.410 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.417 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestResponseBodyMethodProcessor - Written [PagedResource { content: [links: [<http://localhost/metrics/counters/bar>;rel="self"], links: [<http://localhost/metrics/counters/foo>;rel="self"]], metadata: Metadata { number: 0, total pages: 1, total elements: 2, size: 20 }, links: [<http://localhost/metrics/counters?page=0&size=20>;rel="self"] }] as "application/json" using [org.springframework.http.converter.json.MappingJackson2HttpMessageConverter@de7e193]
19-Apr-2018 00:55:26 00:55:26.418 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Null ModelAndView returned to DispatcherServlet with name '': assuming HandlerAdapter completed request handling
19-Apr-2018 00:55:26 00:55:26.418 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Successfully completed request
19-Apr-2018 00:55:26 00:55:26.418 [main] DEBUG com.jayway.jsonpath.internal.path.CompiledPath - Evaluating path: $['page']['totalElements']
19-Apr-2018 00:55:26 00:55:26.419 [main] DEBUG com.jayway.jsonpath.internal.path.CompiledPath - Evaluating path: $['content'][*]['name']
19-Apr-2018 00:55:26 00:55:26.419 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.419 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.419 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.419 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.419 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.420 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.420 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 70 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.420 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.420 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.420 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZRANGE, output=ValueListOutput [output=[[B@5306de25, [B@729581e], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.421 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.421 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.421 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.421 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.421 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.421 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.421 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.422 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 44 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.422 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.422 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.422 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=MGET, output=KeyValueListOutput [output=[KeyValue[[B@688fa2b5, [B@1811c584], KeyValue[[B@7d03d755, [B@30a79943]], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.422 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.422 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.422 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.422 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.423 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.423 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.423 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.423 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.423 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.423 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.424 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=1.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.424 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.424 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.424 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.424 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.424 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.424 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.425 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.425 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.425 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.425 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.425 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=1.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.425 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.426 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.426 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.426 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.427 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.427 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.427 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.427 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.427 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.427 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.428 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZREM, output=IntegerOutput [output=1, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.428 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.429 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.430 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.431 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.431 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.431 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.431 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.432 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.432 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.432 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.432 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=DEL, output=IntegerOutput [output=1, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.432 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.432 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.432 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.433 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.433 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.433 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.433 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.433 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.433 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.434 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.434 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZREM, output=IntegerOutput [output=1, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.434 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.434 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:26 00:55:26.434 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.434 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.434 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:26 00:55:26.434 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:26 00:55:26.435 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:26 00:55:26.435 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
19-Apr-2018 00:55:26 00:55:26.435 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:26 00:55:26.435 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:26 00:55:26.435 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=DEL, output=IntegerOutput [output=1, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:26 00:55:26.435 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:26 00:55:26.435 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:26 00:55:26.436 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 25, missCount = 3]
19-Apr-2018 00:55:26 00:55:26.436 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:26 00:55:26.436 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 26, missCount = 3]
19-Apr-2018 00:55:26 00:55:26.437 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:26 00:55:26.437 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 27, missCount = 3]
19-Apr-2018 00:55:26 00:55:26.437 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.test.mock.mockito.MockitoBeans'
19-Apr-2018 00:55:26 00:55:26.437 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - After test method: context [DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@2c63762b, testMethod = testList@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.populatedRequestContextHolder' -> true, 'org.springframework.test.context.web.ServletTestExecutionListener.resetRequestContextHolder' -> true]], class annotated with @DirtiesContext [false] with mode [null], method annotated with @DirtiesContext [false] with mode [null].
19-Apr-2018 00:55:26 00:55:26.437 [main] DEBUG org.springframework.test.context.web.ServletTestExecutionListener - Resetting RequestContextHolder for test context [DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@2c63762b, testMethod = testList@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.populatedRequestContextHolder' -> true, 'org.springframework.test.context.web.ServletTestExecutionListener.resetRequestContextHolder' -> true]].
19-Apr-2018 00:55:26 00:55:26.437 [main] DEBUG io.lettuce.core.RedisChannelHandler - close()
19-Apr-2018 00:55:26 00:55:26.437 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, epid=0x3] close()
19-Apr-2018 00:55:26 00:55:26.438 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, chid=0x3] channelInactive()
19-Apr-2018 00:55:26 00:55:26.438 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, epid=0x3] deactivating endpoint handler
19-Apr-2018 00:55:26 00:55:26.438 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, chid=0x3] channelInactive() done
19-Apr-2018 00:55:26 00:55:26.438 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
19-Apr-2018 00:55:26 00:55:26.438 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
19-Apr-2018 00:55:26 00:55:26.438 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
19-Apr-2018 00:55:26 00:55:26.438 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
19-Apr-2018 00:55:26 00:55:26.438 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x5ea89297, /127.0.0.1:36270 -> localhost/127.0.0.1:6379, chid=0x3] channelUnregistered()
19-Apr-2018 00:55:27 00:55:27.542 [lettuce-nioEventLoop-12-1] DEBUG io.netty.buffer.PoolThreadCache - Freed 1 thread-local buffer(s) from thread: lettuce-nioEventLoop-12-1
19-Apr-2018 00:55:27 00:55:27.544 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [org.springframework.analytics.rest.controller.CounterControllerTests]
19-Apr-2018 00:55:27 00:55:27.544 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [org.springframework.analytics.rest.controller.CounterControllerTests]
19-Apr-2018 00:55:27 00:55:27.545 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:27 00:55:27.545 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 28, missCount = 3]
19-Apr-2018 00:55:27 00:55:27.545 [main] DEBUG org.springframework.test.context.web.ServletTestExecutionListener - Setting up MockHttpServletRequest, MockHttpServletResponse, ServletWebRequest, and RequestContextHolder for test context [DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@5a97b17c, testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.support.DependencyInjectionTestExecutionListener.reinjectDependencies' -> true]].
19-Apr-2018 00:55:27 00:55:27.546 [main] DEBUG org.springframework.test.context.support.DependencyInjectionTestExecutionListener - Performing dependency injection for test context [[DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@5a97b17c, testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.populatedRequestContextHolder' -> true, 'org.springframework.test.context.web.ServletTestExecutionListener.resetRequestContextHolder' -> true, 'org.springframework.test.context.support.DependencyInjectionTestExecutionListener.reinjectDependencies' -> true]]].
19-Apr-2018 00:55:27 00:55:27.546 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:27 00:55:27.546 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 29, missCount = 3]
19-Apr-2018 00:55:27 00:55:27.546 [main] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata - Processing injected element of bean 'org.springframework.analytics.rest.controller.CounterControllerTests': AutowiredFieldElement for private org.springframework.analytics.metrics.redis.RedisMetricRepository org.springframework.analytics.rest.controller.CounterControllerTests.repository
19-Apr-2018 00:55:27 00:55:27.547 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation [interface org.springframework.beans.factory.annotation.Autowired]: java.lang.NullPointerException
19-Apr-2018 00:55:27 00:55:27.547 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterRepository'
19-Apr-2018 00:55:27 00:55:27.547 [main] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata - Processing injected element of bean 'org.springframework.analytics.rest.controller.CounterControllerTests': AutowiredFieldElement for private org.springframework.analytics.test.support.CounterService org.springframework.analytics.rest.controller.CounterControllerTests.counterService
19-Apr-2018 00:55:27 00:55:27.547 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation [interface org.springframework.beans.factory.annotation.Autowired]: java.lang.NullPointerException
19-Apr-2018 00:55:27 00:55:27.547 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterService'
19-Apr-2018 00:55:27 00:55:27.549 [main] DEBUG org.springframework.beans.factory.annotation.InjectionMetadata - Processing injected element of bean 'org.springframework.analytics.rest.controller.CounterControllerTests': AutowiredFieldElement for private org.springframework.web.context.WebApplicationContext org.springframework.analytics.rest.controller.CounterControllerTests.wac
19-Apr-2018 00:55:27 00:55:27.549 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation [interface org.springframework.beans.factory.annotation.Autowired]: java.lang.NullPointerException
19-Apr-2018 00:55:27 00:55:27.550 [main] DEBUG io.lettuce.core.RedisClient - Trying to get a Redis connection for: RedisURI [host='localhost', port=6379]
19-Apr-2018 00:55:27 00:55:27.551 [main] DEBUG io.lettuce.core.resource.DefaultEventLoopGroupProvider - Adding reference to io.netty.channel.nio.NioEventLoopGroup@427a12b6, existing ref count 0
19-Apr-2018 00:55:27 00:55:27.551 [main] DEBUG io.lettuce.core.RedisClient - Resolved SocketAddress localhost:6379 using RedisURI [host='localhost', port=6379]
19-Apr-2018 00:55:27 00:55:27.551 [main] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379
19-Apr-2018 00:55:27 00:55:27.553 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x0291300f, [id: 0x46d6d74a] (inactive), chid=0x5] channelRegistered()
19-Apr-2018 00:55:27 00:55:27.553 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, chid=0x5] channelActive()
19-Apr-2018 00:55:27 00:55:27.554 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, epid=0x5] activateEndpointAndExecuteBufferedCommands 0 command(s) buffered
19-Apr-2018 00:55:27 00:55:27.554 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, epid=0x5] activating endpoint
19-Apr-2018 00:55:27 00:55:27.554 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, epid=0x5] flushCommands()
19-Apr-2018 00:55:27 00:55:27.554 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, epid=0x5] flushCommands() Flushing 0 commands
19-Apr-2018 00:55:27 00:55:27.554 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
19-Apr-2018 00:55:27 00:55:27.554 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
19-Apr-2018 00:55:27 00:55:27.554 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, chid=0x5] channelActive() done
19-Apr-2018 00:55:27 00:55:27.554 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379: Success
19-Apr-2018 00:55:27 00:55:27.555 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@7ef23a15)
19-Apr-2018 00:55:27 00:55:27.555 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@7ef23a15)
19-Apr-2018 00:55:27 00:55:27.556 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - Before test method: context [DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@5a97b17c, testMethod = testGetAndDelete@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.populatedRequestContextHolder' -> true, 'org.springframework.test.context.web.ServletTestExecutionListener.resetRequestContextHolder' -> true]], class annotated with @DirtiesContext [false] with mode [null], method annotated with @DirtiesContext [false] with mode [null].
19-Apr-2018 00:55:27 00:55:27.556 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:27 00:55:27.556 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 30, missCount = 3]
19-Apr-2018 00:55:27 00:55:27.557 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.test.mock.mockito.MockitoBeans'
19-Apr-2018 00:55:27 00:55:27.557 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Initializing servlet ''
19-Apr-2018 00:55:27 00:55:27.557 [main] INFO org.springframework.mock.web.MockServletContext - Initializing Spring FrameworkServlet ''
19-Apr-2018 00:55:27 00:55:27.557 [main] INFO org.springframework.test.web.servlet.TestDispatcherServlet - FrameworkServlet '': initialization started
19-Apr-2018 00:55:27 00:55:27.558 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate MultipartResolver with name 'multipartResolver': no multipart request handling provided
19-Apr-2018 00:55:27 00:55:27.558 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
19-Apr-2018 00:55:27 00:55:27.558 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
19-Apr-2018 00:55:27 00:55:27.559 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate LocaleResolver with name 'localeResolver': using default [org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver@6025d790]
19-Apr-2018 00:55:27 00:55:27.559 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
19-Apr-2018 00:55:27 00:55:27.560 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
19-Apr-2018 00:55:27 00:55:27.560 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate ThemeResolver with name 'themeResolver': using default [org.springframework.web.servlet.theme.FixedThemeResolver@af7e376]
19-Apr-2018 00:55:27 00:55:27.560 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerMapping'
19-Apr-2018 00:55:27 00:55:27.560 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'viewControllerHandlerMapping'
19-Apr-2018 00:55:27 00:55:27.560 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'beanNameHandlerMapping'
19-Apr-2018 00:55:27 00:55:27.560 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'resourceHandlerMapping'
19-Apr-2018 00:55:27 00:55:27.560 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'defaultServletHandlerMapping'
19-Apr-2018 00:55:27 00:55:27.561 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerAdapter'
19-Apr-2018 00:55:27 00:55:27.561 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'httpRequestHandlerAdapter'
19-Apr-2018 00:55:27 00:55:27.561 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'simpleControllerHandlerAdapter'
19-Apr-2018 00:55:27 00:55:27.561 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'handlerExceptionResolver'
19-Apr-2018 00:55:27 00:55:27.561 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
19-Apr-2018 00:55:27 00:55:27.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
19-Apr-2018 00:55:27 00:55:27.562 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate RequestToViewNameTranslator with name 'viewNameTranslator': using default [org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator@5dcd0cdf]
19-Apr-2018 00:55:27 00:55:27.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcViewResolver'
19-Apr-2018 00:55:27 00:55:27.563 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
19-Apr-2018 00:55:27 00:55:27.563 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
19-Apr-2018 00:55:27 00:55:27.563 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate FlashMapManager with name 'flashMapManager': using default [org.springframework.web.servlet.support.SessionFlashMapManager@4fb04a72]
19-Apr-2018 00:55:27 00:55:27.563 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Published WebApplicationContext of servlet '' as ServletContext attribute with name [org.springframework.web.servlet.FrameworkServlet.CONTEXT.]
19-Apr-2018 00:55:27 00:55:27.563 [main] INFO org.springframework.test.web.servlet.TestDispatcherServlet - FrameworkServlet '': initialization completed in 5 ms
19-Apr-2018 00:55:27 00:55:27.563 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Servlet '' configured successfully
19-Apr-2018 00:55:27 00:55:27.563 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.564 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.564 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.564 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.564 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.565 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.565 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.565 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.565 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.565 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=0.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.567 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.567 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.568 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.568 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.568 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.568 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.569 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.569 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.569 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.569 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.569 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=1.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.569 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.569 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.570 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.570 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.570 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.570 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.571 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.571 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.571 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.571 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.571 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=SET, output=StatusOutput [output=OK, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.571 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.571 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.571 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.572 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.572 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.572 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.572 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.573 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.573 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.573 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.573 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=1.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.573 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.573 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.573 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.573 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.573 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.574 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.575 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.576 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.576 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.576 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.576 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZINCRBY, output=DoubleOutput [output=2.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.576 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.576 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.576 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.576 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.576 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.576 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.577 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.577 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.577 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.578 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.578 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=SET, output=StatusOutput [output=OK, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.578 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.578 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - DispatcherServlet with name '' processing GET request for [/metrics/counters/foo]
19-Apr-2018 00:55:27 00:55:27.579 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Looking up handler method for path /metrics/counters/foo
19-Apr-2018 00:55:27 00:55:27.579 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Returning handler method [public org.springframework.analytics.rest.domain.CounterResource org.springframework.analytics.rest.controller.CounterController.display(java.lang.String)]
19-Apr-2018 00:55:27 00:55:27.579 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterController'
19-Apr-2018 00:55:27 00:55:27.580 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Last-Modified value for [/metrics/counters/foo] is: -1
19-Apr-2018 00:55:27 00:55:27.581 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.581 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.581 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.581 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.581 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.582 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.582 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 20 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.582 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.582 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.582 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=GET, output=ValueOutput [output=[B@50d91366, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.583 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.583 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.583 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.583 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.583 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.583 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.584 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.584 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.584 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.584 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.584 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=2.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.584 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.589 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestResponseBodyMethodProcessor - Written [links: [<http://localhost/metrics/counters/foo>;rel="self"]] as "application/json" using [org.springframework.http.converter.json.MappingJackson2HttpMessageConverter@de7e193]
19-Apr-2018 00:55:27 00:55:27.589 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Null ModelAndView returned to DispatcherServlet with name '': assuming HandlerAdapter completed request handling
19-Apr-2018 00:55:27 00:55:27.589 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Successfully completed request
19-Apr-2018 00:55:27 00:55:27.589 [main] DEBUG com.jayway.jsonpath.internal.path.CompiledPath - Evaluating path: $['name']
19-Apr-2018 00:55:27 00:55:27.590 [main] DEBUG com.jayway.jsonpath.internal.path.CompiledPath - Evaluating path: $['value']
19-Apr-2018 00:55:27 00:55:27.590 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - DispatcherServlet with name '' processing DELETE request for [/metrics/counters/foo]
19-Apr-2018 00:55:27 00:55:27.590 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Looking up handler method for path /metrics/counters/foo
19-Apr-2018 00:55:27 00:55:27.590 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Returning handler method [protected void org.springframework.analytics.rest.controller.CounterController.delete(java.lang.String)]
19-Apr-2018 00:55:27 00:55:27.591 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterController'
19-Apr-2018 00:55:27 00:55:27.591 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.591 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.591 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.592 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.592 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.592 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.593 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 20 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.593 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.593 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.593 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=GET, output=ValueOutput [output=[B@5cb8f5bf, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.593 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.593 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.593 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.593 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.593 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.593 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.594 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.594 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.594 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.594 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.594 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=2.0, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.595 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.595 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.595 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.595 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.595 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.595 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.596 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.596 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.596 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.596 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.596 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZREM, output=IntegerOutput [output=1, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.596 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.596 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.597 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.597 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.597 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.597 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.597 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.598 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.598 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.598 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.598 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=DEL, output=IntegerOutput [output=1, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.598 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.598 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Null ModelAndView returned to DispatcherServlet with name '': assuming HandlerAdapter completed request handling
19-Apr-2018 00:55:27 00:55:27.598 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Successfully completed request
19-Apr-2018 00:55:27 00:55:27.599 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - DispatcherServlet with name '' processing GET request for [/metrics/counters/foo]
19-Apr-2018 00:55:27 00:55:27.599 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Looking up handler method for path /metrics/counters/foo
19-Apr-2018 00:55:27 00:55:27.599 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Returning handler method [public org.springframework.analytics.rest.domain.CounterResource org.springframework.analytics.rest.controller.CounterController.display(java.lang.String)]
19-Apr-2018 00:55:27 00:55:27.599 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterController'
19-Apr-2018 00:55:27 00:55:27.600 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Last-Modified value for [/metrics/counters/foo] is: -1
19-Apr-2018 00:55:27 00:55:27.600 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.600 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.600 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.600 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.600 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.601 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.601 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.601 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.601 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.601 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.601 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.601 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.602 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.602 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.602 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.602 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.602 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.603 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.603 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.603 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.603 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.603 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.603 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.ExceptionHandlerExceptionResolver - Resolving exception from handler [public org.springframework.analytics.rest.domain.CounterResource org.springframework.analytics.rest.controller.CounterController.display(java.lang.String)]: org.springframework.analytics.rest.controller.NoSuchMetricException: foo
19-Apr-2018 00:55:27 00:55:27.604 [main] DEBUG org.springframework.web.servlet.mvc.annotation.ResponseStatusExceptionResolver - Resolving exception from handler [public org.springframework.analytics.rest.domain.CounterResource org.springframework.analytics.rest.controller.CounterController.display(java.lang.String)]: org.springframework.analytics.rest.controller.NoSuchMetricException: foo
19-Apr-2018 00:55:27 00:55:27.604 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Null ModelAndView returned to DispatcherServlet with name '': assuming HandlerAdapter completed request handling
19-Apr-2018 00:55:27 00:55:27.604 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Successfully completed request
19-Apr-2018 00:55:27 00:55:27.604 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
19-Apr-2018 00:55:27 00:55:27.605 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.605 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() writeAndFlush command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.605 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] write(ctx, AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command], promise)
19-Apr-2018 00:55:27 00:55:27.605 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] write() done
19-Apr-2018 00:55:27 00:55:27.605 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
19-Apr-2018 00:55:27 00:55:27.606 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
19-Apr-2018 00:55:27 00:55:27.606 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
19-Apr-2018 00:55:27 00:55:27.606 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decode LatencyMeteredCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
19-Apr-2018 00:55:27 00:55:27.606 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
19-Apr-2018 00:55:27 00:55:27.606 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
19-Apr-2018 00:55:27 00:55:27.606 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:27 00:55:27.606 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 31, missCount = 3]
19-Apr-2018 00:55:27 00:55:27.607 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:27 00:55:27.607 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 32, missCount = 3]
19-Apr-2018 00:55:27 00:55:27.608 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
19-Apr-2018 00:55:27 00:55:27.608 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@57540fd0 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 33, missCount = 3]
19-Apr-2018 00:55:27 00:55:27.608 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.test.mock.mockito.MockitoBeans'
19-Apr-2018 00:55:27 00:55:27.609 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - After test method: context [DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@5a97b17c, testMethod = testGetAndDelete@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.populatedRequestContextHolder' -> true, 'org.springframework.test.context.web.ServletTestExecutionListener.resetRequestContextHolder' -> true]], class annotated with @DirtiesContext [false] with mode [null], method annotated with @DirtiesContext [false] with mode [null].
19-Apr-2018 00:55:27 00:55:27.609 [main] DEBUG org.springframework.test.context.web.ServletTestExecutionListener - Resetting RequestContextHolder for test context [DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@5a97b17c, testMethod = testGetAndDelete@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.web.ServletTestExecutionListener.populatedRequestContextHolder' -> true, 'org.springframework.test.context.web.ServletTestExecutionListener.resetRequestContextHolder' -> true]].
19-Apr-2018 00:55:27 00:55:27.609 [main] DEBUG io.lettuce.core.RedisChannelHandler - close()
19-Apr-2018 00:55:27 00:55:27.609 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, epid=0x5] close()
19-Apr-2018 00:55:27 00:55:27.610 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, chid=0x5] channelInactive()
19-Apr-2018 00:55:27 00:55:27.610 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, epid=0x5] deactivating endpoint handler
19-Apr-2018 00:55:27 00:55:27.610 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, chid=0x5] channelInactive() done
19-Apr-2018 00:55:27 00:55:27.610 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
19-Apr-2018 00:55:27 00:55:27.610 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
19-Apr-2018 00:55:27 00:55:27.610 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
19-Apr-2018 00:55:27 00:55:27.610 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
19-Apr-2018 00:55:27 00:55:27.610 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x0291300f, /127.0.0.1:36274 -> localhost/127.0.0.1:6379, chid=0x5] channelUnregistered()
19-Apr-2018 00:55:28 00:55:28.712 [lettuce-nioEventLoop-16-1] DEBUG io.netty.buffer.PoolThreadCache - Freed 1 thread-local buffer(s) from thread: lettuce-nioEventLoop-16-1
19-Apr-2018 00:55:28 00:55:28.716 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - After test class: context [DefaultTestContext@3bed3315 testClass = CounterControllerTests, testInstance = [null], testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@119b0892 testClass = CounterControllerTests, locations = '{}', classes = '{class org.springframework.analytics.rest.controller.CounterControllerTests$Config}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@1da51a35, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@71623278, org.springframework.boot.test.mock.mockito.MockitoContextCustomizer@0, org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer@0, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverContextCustomizerFactory$Customizer@530612ba, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@3bd94634], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.support.DependencyInjectionTestExecutionListener.reinjectDependencies' -> true]], class annotated with @DirtiesContext [false] with mode [null].
19-Apr-2018 00:55:28 Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.99 sec - in org.springframework.analytics.rest.controller.CounterControllerTests
19-Apr-2018 00:55:28 00:55:28.719 [Thread-2] INFO org.springframework.web.context.support.GenericWebApplicationContext - Closing org.springframework.web.context.support.GenericWebApplicationContext@3c35c345: startup date [Thu Apr 19 00:55:22 UTC 2018]; root of context hierarchy
19-Apr-2018 00:55:28 00:55:28.720 [Thread-1] INFO org.springframework.web.context.support.GenericWebApplicationContext - Closing org.springframework.web.context.support.GenericWebApplicationContext@7b9a4292: startup date [Thu Apr 19 00:55:19 UTC 2018]; root of context hierarchy
19-Apr-2018 00:55:28 00:55:28.721 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'
19-Apr-2018 00:55:28 00:55:28.721 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@291b4bf5: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.event.internalEventListenerProcessor,org.springframework.context.event.internalEventListenerFactory,aggregateCounterControllerTests.Config,org.springframework.boot.test.mock.mockito.MockitoPostProcessor$SpyPostProcessor,org.springframework.boot.test.mock.mockito.MockitoPostProcessor,org.springframework.data.web.config.ProjectingArgumentResolverRegistrar,projectingArgumentResolverBeanPostProcessor,org.springframework.data.web.config.HateoasAwareSpringDataWebConfiguration,pageableResolver,sortResolver,pagedResourcesAssembler,pagedResourcesAssemblerArgumentResolver,org.springframework.data.web.config.SpringDataJacksonConfiguration,jacksonGeoModule,org.springframework.hateoas.config.HateoasConfiguration,linkRelationMessageSource,org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration,requestMappingHandlerMapping,mvcPathMatcher,mvcUrlPathHelper,mvcContentNegotiationManager,viewControllerHandlerMapping,beanNameHandlerMapping,resourceHandlerMapping,mvcResourceUrlProvider,defaultServletHandlerMapping,requestMappingHandlerAdapter,mvcConversionService,mvcValidator,mvcUriComponentsContributor,httpRequestHandlerAdapter,simpleControllerHandlerAdapter,handlerExceptionResolver,mvcViewResolver,mvcHandlerMappingIntrospector,aggregateCounterRepository,aggregateCounterController,entityLinksPluginRegistry,controllerEntityLinks,delegatingEntityLinks,org.springframework.hateoas.hal.HalLinkDiscoverer#0,_halObjectMapper,org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$DefaultObjectMapperCustomizer#0,org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$Jackson2ModuleRegisteringBeanPostProcessor#0,org.springframework.hateoas.hal.HalConfiguration#0,_linkDiscovererRegistry,org.springframework.hateoas.LinkDiscoverers#0,defaultRelProvider,annotationRelProvider,relProviderPluginRegistry,_relProvider]; root of factory hierarchy
19-Apr-2018 00:55:28 00:55:28.722 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '_linkDiscovererRegistry': [org.springframework.hateoas.LinkDiscoverers#0]
19-Apr-2018 00:55:28 00:55:28.722 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#3c89bb12': [delegatingEntityLinks]
19-Apr-2018 00:55:28 00:55:28.722 [Thread-1] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'controllerEntityLinks'
19-Apr-2018 00:55:28 00:55:28.722 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#2a49fe': [controllerEntityLinks]
19-Apr-2018 00:55:28 00:55:28.722 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#5183d589': [_relProvider]
19-Apr-2018 00:55:28 00:55:28.722 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcValidator': [requestMappingHandlerAdapter]
19-Apr-2018 00:55:28 00:55:28.722 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'requestMappingHandlerAdapter': [mvcUriComponentsContributor]
19-Apr-2018 00:55:28 00:55:28.722 [Thread-1] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'mvcValidator'
19-Apr-2018 00:55:28 00:55:28.723 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcResourceUrlProvider': [requestMappingHandlerMapping]
19-Apr-2018 00:55:28 00:55:28.726 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'
19-Apr-2018 00:55:28 00:55:28.726 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@69a2b3b6: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.event.internalEventListenerProcessor,org.springframework.context.event.internalEventListenerFactory,fieldValueCounterControllerTests.Config,org.springframework.boot.test.mock.mockito.MockitoPostProcessor$SpyPostProcessor,org.springframework.boot.test.mock.mockito.MockitoPostProcessor,org.springframework.data.web.config.ProjectingArgumentResolverRegistrar,projectingArgumentResolverBeanPostProcessor,org.springframework.data.web.config.HateoasAwareSpringDataWebConfiguration,pageableResolver,sortResolver,pagedResourcesAssembler,pagedResourcesAssemblerArgumentResolver,org.springframework.data.web.config.SpringDataJacksonConfiguration,jacksonGeoModule,org.springframework.hateoas.config.HateoasConfiguration,linkRelationMessageSource,org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration,requestMappingHandlerMapping,mvcPathMatcher,mvcUrlPathHelper,mvcContentNegotiationManager,viewControllerHandlerMapping,beanNameHandlerMapping,resourceHandlerMapping,mvcResourceUrlProvider,defaultServletHandlerMapping,requestMappingHandlerAdapter,mvcConversionService,mvcValidator,mvcUriComponentsContributor,httpRequestHandlerAdapter,simpleControllerHandlerAdapter,handlerExceptionResolver,mvcViewResolver,mvcHandlerMappingIntrospector,fieldValueCounterRepository,fieldValueCounterController,entityLinksPluginRegistry,controllerEntityLinks,delegatingEntityLinks,org.springframework.hateoas.hal.HalLinkDiscoverer#0,_halObjectMapper,org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$DefaultObjectMapperCustomizer#0,org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$Jackson2ModuleRegisteringBeanPostProcessor#0,org.springframework.hateoas.hal.HalConfiguration#0,_linkDiscovererRegistry,org.springframework.hateoas.LinkDiscoverers#0,defaultRelProvider,annotationRelProvider,relProviderPluginRegistry,_relProvider]; root of factory hierarchy
19-Apr-2018 00:55:28 00:55:28.727 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '_linkDiscovererRegistry': [org.springframework.hateoas.LinkDiscoverers#0]
19-Apr-2018 00:55:28 00:55:28.727 [Thread-4] INFO org.springframework.web.context.support.GenericWebApplicationContext - Closing org.springframework.web.context.support.GenericWebApplicationContext@1a3e8e24: startup date [Thu Apr 19 00:55:25 UTC 2018]; root of context hierarchy
19-Apr-2018 00:55:28 00:55:28.728 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'
19-Apr-2018 00:55:28 00:55:28.728 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@7f9fc8bd: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.event.internalEventListenerProcessor,org.springframework.context.event.internalEventListenerFactory,counterControllerTests.Config,org.springframework.boot.test.mock.mockito.MockitoPostProcessor$SpyPostProcessor,org.springframework.boot.test.mock.mockito.MockitoPostProcessor,org.springframework.data.web.config.ProjectingArgumentResolverRegistrar,projectingArgumentResolverBeanPostProcessor,org.springframework.data.web.config.HateoasAwareSpringDataWebConfiguration,pageableResolver,sortResolver,pagedResourcesAssembler,pagedResourcesAssemblerArgumentResolver,org.springframework.data.web.config.SpringDataJacksonConfiguration,jacksonGeoModule,org.springframework.hateoas.config.HateoasConfiguration,linkRelationMessageSource,org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration,requestMappingHandlerMapping,mvcPathMatcher,mvcUrlPathHelper,mvcContentNegotiationManager,viewControllerHandlerMapping,beanNameHandlerMapping,resourceHandlerMapping,mvcResourceUrlProvider,defaultServletHandlerMapping,requestMappingHandlerAdapter,mvcConversionService,mvcValidator,mvcUriComponentsContributor,httpRequestHandlerAdapter,simpleControllerHandlerAdapter,handlerExceptionResolver,mvcViewResolver,mvcHandlerMappingIntrospector,org.springframework.boot.autoconfigure.data.redis.LettuceConnectionConfiguration,org.springframework.boot.autoconfigure.condition.BeanTypeRegistry,lettuceClientResources,redisConnectionFactory,org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration,redisTemplate,stringRedisTemplate,spring.redis-org.springframework.boot.autoconfigure.data.redis.RedisProperties,org.springframework.boot.context.properties.ConfigurationPropertiesBindingPostProcessor,org.springframework.boot.context.properties.ConfigurationBeanFactoryMetadata,counterRepository,counterController,counterService,entityLinksPluginRegistry,controllerEntityLinks,delegatingEntityLinks,org.springframework.hateoas.hal.HalLinkDiscoverer#0,_halObjectMapper,org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$DefaultObjectMapperCustomizer#0,org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$Jackson2ModuleRegisteringBeanPostProcessor#0,org.springframework.hateoas.hal.HalConfiguration#0,_linkDiscovererRegistry,org.springframework.hateoas.LinkDiscoverers#0,defaultRelProvider,annotationRelProvider,relProviderPluginRegistry,_relProvider]; root of factory hierarchy
19-Apr-2018 00:55:28 00:55:28.728 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#11b455e5': [delegatingEntityLinks]
19-Apr-2018 00:55:28 00:55:28.737 [Thread-2] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'controllerEntityLinks'
19-Apr-2018 00:55:28 00:55:28.737 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '_linkDiscovererRegistry': [org.springframework.hateoas.LinkDiscoverers#0]
19-Apr-2018 00:55:28 00:55:28.737 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#12968227': [controllerEntityLinks]
19-Apr-2018 00:55:28 00:55:28.737 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#fabb651': [_relProvider]
19-Apr-2018 00:55:28 00:55:28.737 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcValidator': [requestMappingHandlerAdapter]
19-Apr-2018 00:55:28 00:55:28.737 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'requestMappingHandlerAdapter': [mvcUriComponentsContributor]
19-Apr-2018 00:55:28 00:55:28.737 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#798dad3d': [delegatingEntityLinks]
19-Apr-2018 00:55:28 00:55:28.737 [Thread-2] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'mvcValidator'
19-Apr-2018 00:55:28 00:55:28.737 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcResourceUrlProvider': [requestMappingHandlerMapping]
19-Apr-2018 00:55:28 00:55:28.738 [Thread-4] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'controllerEntityLinks'
19-Apr-2018 00:55:28 00:55:28.738 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#72976b4': [controllerEntityLinks]
19-Apr-2018 00:55:28 00:55:28.738 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#52b46d52': [_relProvider]
19-Apr-2018 00:55:28 00:55:28.738 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcValidator': [requestMappingHandlerAdapter]
19-Apr-2018 00:55:28 00:55:28.738 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'requestMappingHandlerAdapter': [mvcUriComponentsContributor]
19-Apr-2018 00:55:28 00:55:28.738 [Thread-4] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'mvcValidator'
19-Apr-2018 00:55:28 00:55:28.739 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcResourceUrlProvider': [requestMappingHandlerMapping]
19-Apr-2018 00:55:28 00:55:28.739 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'redisConnectionFactory': [counterControllerTests.Config, redisTemplate, stringRedisTemplate]
19-Apr-2018 00:55:28 00:55:28.739 [Thread-4] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'redisConnectionFactory'
19-Apr-2018 00:55:28 00:55:28.739 [Thread-4] DEBUG io.lettuce.core.RedisChannelHandler - close()
19-Apr-2018 00:55:28 00:55:28.739 [Thread-4] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] close()
19-Apr-2018 00:55:28 00:55:28.739 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] channelInactive()
19-Apr-2018 00:55:28 00:55:28.739 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, epid=0x4] deactivating endpoint handler
19-Apr-2018 00:55:28 00:55:28.739 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] channelInactive() done
19-Apr-2018 00:55:28 00:55:28.739 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
19-Apr-2018 00:55:28 00:55:28.739 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
19-Apr-2018 00:55:28 00:55:28.740 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
19-Apr-2018 00:55:28 00:55:28.740 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
19-Apr-2018 00:55:28 00:55:28.740 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xb952e2c1, /127.0.0.1:36272 -> localhost/127.0.0.1:6379, chid=0x4] channelUnregistered()
19-Apr-2018 00:55:29 00:55:29.842 [lettuce-nioEventLoop-13-1] DEBUG io.netty.buffer.PoolThreadCache - Freed 20 thread-local buffer(s) from thread: lettuce-nioEventLoop-13-1
19-Apr-2018 00:55:29 00:55:29.843 [Thread-4] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy method 'shutdown' on bean with name 'lettuceClientResources'
19-Apr-2018 00:55:30
19-Apr-2018 00:55:30 Results :
19-Apr-2018 00:55:30
19-Apr-2018 00:55:30 Tests run: 6, Failures: 0, Errors: 0, Skipped: 0
19-Apr-2018 00:55:30
19-Apr-2018 00:55:30 [INFO]
19-Apr-2018 00:55:30 [INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ spring-analytics ---
19-Apr-2018 00:55:30 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/spring-analytics-2.0.0.BUILD-SNAPSHOT.jar
19-Apr-2018 00:55:30 [INFO]
19-Apr-2018 00:55:30 [INFO] --- maven-javadoc-plugin:2.10.4:jar (javadoc) @ spring-analytics ---
19-Apr-2018 00:55:31 [WARNING] Source files encoding has not been set, using platform encoding UTF-8, i.e. build is platform dependent!
19-Apr-2018 00:55:33 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/spring-analytics-2.0.0.BUILD-SNAPSHOT-javadoc.jar
19-Apr-2018 00:55:33 [INFO]
19-Apr-2018 00:55:33 [INFO] >>> maven-source-plugin:3.0.1:jar (attach-sources) > generate-sources @ spring-analytics >>>
19-Apr-2018 00:55:33 [INFO]
19-Apr-2018 00:55:33 [INFO] <<< maven-source-plugin:3.0.1:jar (attach-sources) < generate-sources @ spring-analytics <<<
19-Apr-2018 00:55:33 [INFO]
19-Apr-2018 00:55:33 [INFO] --- maven-source-plugin:3.0.1:jar (attach-sources) @ spring-analytics ---
19-Apr-2018 00:55:33 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/spring-analytics-2.0.0.BUILD-SNAPSHOT-sources.jar
19-Apr-2018 00:55:33 [INFO]
19-Apr-2018 00:55:33 [INFO] --- maven-install-plugin:2.4:install (default-install) @ spring-analytics ---
19-Apr-2018 00:55:34 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/spring-analytics-2.0.0.BUILD-SNAPSHOT.jar to /home/bamboo/.m2/repository/org/springframework/analytics/spring-analytics/2.0.0.BUILD-SNAPSHOT/spring-analytics-2.0.0.BUILD-SNAPSHOT.jar
19-Apr-2018 00:55:34 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/pom.xml to /home/bamboo/.m2/repository/org/springframework/analytics/spring-analytics/2.0.0.BUILD-SNAPSHOT/spring-analytics-2.0.0.BUILD-SNAPSHOT.pom
19-Apr-2018 00:55:34 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/spring-analytics-2.0.0.BUILD-SNAPSHOT-javadoc.jar to /home/bamboo/.m2/repository/org/springframework/analytics/spring-analytics/2.0.0.BUILD-SNAPSHOT/spring-analytics-2.0.0.BUILD-SNAPSHOT-javadoc.jar
19-Apr-2018 00:55:34 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/spring-analytics-2.0.0.BUILD-SNAPSHOT-sources.jar to /home/bamboo/.m2/repository/org/springframework/analytics/spring-analytics/2.0.0.BUILD-SNAPSHOT/spring-analytics-2.0.0.BUILD-SNAPSHOT-sources.jar
19-Apr-2018 00:55:34 [INFO] Artifactory Build Info Recorder: Saving Build Info to '/opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/build-info.json'
19-Apr-2018 00:55:34 [INFO] Deploying artifact: https://repo.spring.io/libs-snapshot-local/org/springframework/analytics/spring-analytics/2.0.0.BUILD-SNAPSHOT/spring-analytics-2.0.0.BUILD-SNAPSHOT.jar
19-Apr-2018 00:55:35 [INFO] Deploying artifact: https://repo.spring.io/libs-snapshot-local/org/springframework/analytics/spring-analytics/2.0.0.BUILD-SNAPSHOT/spring-analytics-2.0.0.BUILD-SNAPSHOT-javadoc.jar
19-Apr-2018 00:55:36 [INFO] Deploying artifact: https://repo.spring.io/libs-snapshot-local/org/springframework/analytics/spring-analytics/2.0.0.BUILD-SNAPSHOT/spring-analytics-2.0.0.BUILD-SNAPSHOT-sources.jar
19-Apr-2018 00:55:36 [INFO] Deploying artifact: https://repo.spring.io/libs-snapshot-local/org/springframework/analytics/spring-analytics/2.0.0.BUILD-SNAPSHOT/spring-analytics-2.0.0.BUILD-SNAPSHOT.pom
19-Apr-2018 00:55:36 [INFO] Artifactory Build Info Recorder: Deploying build info ...
19-Apr-2018 00:55:36 [INFO] Deploying build descriptor to: https://repo.spring.io/api/build
19-Apr-2018 00:55:36 [INFO] Build successfully deployed. Browse it in Artifactory under https://repo.spring.io/webapp/builds/Spring%20Analytics%20-%20Master%20JDK7%20-%20Build%20and%20publish/44
19-Apr-2018 00:55:36 [INFO] ------------------------------------------------------------------------
19-Apr-2018 00:55:36 [INFO] BUILD SUCCESS
19-Apr-2018 00:55:36 [INFO] ------------------------------------------------------------------------
19-Apr-2018 00:55:36 [INFO] Total time: 36.164 s
19-Apr-2018 00:55:36 [INFO] Finished at: 2018-04-19T00:55:36+00:00
19-Apr-2018 00:55:37 [INFO] Final Memory: 44M/283M
19-Apr-2018 00:55:37 [INFO] ------------------------------------------------------------------------
19-Apr-2018 00:55:37 Parsing test results under /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1...
19-Apr-2018 00:55:37 Finished task 'Build and publish Spring Analytics' with result: Success
19-Apr-2018 00:55:37 Running post build plugin 'Docker Container Cleanup'
19-Apr-2018 00:55:37 Running post build plugin 'NCover Results Collector'
19-Apr-2018 00:55:37 Running post build plugin 'Artifactory Release Management Staging'
19-Apr-2018 00:55:37 Running post build plugin 'Artifactory Build Info Copier'
19-Apr-2018 00:55:37 Copying the buildinfo artifacts for build: SA-BMASTER-JOB1-44
19-Apr-2018 00:55:37 Running post build plugin 'Artifactory Gradle Properties Info Copier'
19-Apr-2018 00:55:37 Running post build plugin 'Clover Results Collector'
19-Apr-2018 00:55:37 Running post build plugin 'npm Cache Cleanup'
19-Apr-2018 00:55:37 Running post build plugin 'Artifact Copier'
19-Apr-2018 00:55:37 Publishing an artifact: Test Results
19-Apr-2018 00:55:37 Finished publishing of artifact Shared artifact: [Test Results], pattern: [**/target/surefire-reports/**/*.*] in 25.94 ms
19-Apr-2018 00:55:37 Successfully removed working directory at '/opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1'
19-Apr-2018 00:55:37 Finalising the build...
19-Apr-2018 00:55:37 Stopping timer.
19-Apr-2018 00:55:37 Build SA-BMASTER-JOB1-44 completed.
19-Apr-2018 00:55:40 Running on server: post build plugin 'NCover Results Collector'
19-Apr-2018 00:55:40 Running on server: post build plugin 'Build Hanging Detection Configuration'
19-Apr-2018 00:55:40 Running on server: post build plugin 'Clover Delta Calculator'
19-Apr-2018 00:55:40 Running on server: post build plugin 'Maven Dependencies Postprocessor'
19-Apr-2018 00:55:40 All post build plugins have finished
19-Apr-2018 00:55:40 Generating build results summary...
19-Apr-2018 00:55:40 Saving build results to disk...
19-Apr-2018 00:55:40 Logging substituted variables...
19-Apr-2018 00:55:40 Indexing build results...
19-Apr-2018 00:55:40 Finished building SA-BMASTER-JOB1-44.