Master build for Spring Analytics

Build: #47 was successful

Job: Build and publish was successful

Stages & jobs

  1. Default Stage

Build log

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

02-May-2018 18:26:45 18:26:45.511 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'beanNameHandlerMapping'
02-May-2018 18:26:45 18:26:45.511 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'beanNameHandlerMapping'
02-May-2018 18:26:45 18:26:45.512 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.512 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'beanNameHandlerMapping' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.513 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Looking for URL mappings in application context: org.springframework.web.context.support.GenericWebApplicationContext@6b63e6ad: startup date [Wed May 02 18:26:45 UTC 2018]; root of context hierarchy
02-May-2018 18:26:45 18:26:45.513 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.internalConfigurationAnnotationProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.514 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.internalAutowiredAnnotationProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.514 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.internalRequiredAnnotationProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.514 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.internalCommonAnnotationProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.514 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.event.internalEventListenerProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.514 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.event.internalEventListenerFactory': no URL paths identified
02-May-2018 18:26:45 18:26:45.514 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'counterControllerTests.Config': no URL paths identified
02-May-2018 18:26:45 18:26:45.514 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.mock.mockito.MockitoPostProcessor$SpyPostProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.514 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.mock.mockito.MockitoPostProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.515 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.data.web.config.ProjectingArgumentResolverRegistrar': no URL paths identified
02-May-2018 18:26:45 18:26:45.515 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'projectingArgumentResolverBeanPostProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.515 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.data.web.config.HateoasAwareSpringDataWebConfiguration': no URL paths identified
02-May-2018 18:26:45 18:26:45.515 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'pageableResolver': no URL paths identified
02-May-2018 18:26:45 18:26:45.515 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'sortResolver': no URL paths identified
02-May-2018 18:26:45 18:26:45.515 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'pagedResourcesAssembler': no URL paths identified
02-May-2018 18:26:45 18:26:45.515 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'pagedResourcesAssemblerArgumentResolver': no URL paths identified
02-May-2018 18:26:45 18:26:45.515 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.data.web.config.SpringDataJacksonConfiguration': no URL paths identified
02-May-2018 18:26:45 18:26:45.516 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'jacksonGeoModule': no URL paths identified
02-May-2018 18:26:45 18:26:45.516 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.config.HateoasConfiguration': no URL paths identified
02-May-2018 18:26:45 18:26:45.516 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'linkRelationMessageSource': no URL paths identified
02-May-2018 18:26:45 18:26:45.516 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration': no URL paths identified
02-May-2018 18:26:45 18:26:45.516 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'requestMappingHandlerMapping': no URL paths identified
02-May-2018 18:26:45 18:26:45.516 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcPathMatcher': no URL paths identified
02-May-2018 18:26:45 18:26:45.516 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcUrlPathHelper': no URL paths identified
02-May-2018 18:26:45 18:26:45.517 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcContentNegotiationManager': no URL paths identified
02-May-2018 18:26:45 18:26:45.517 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'viewControllerHandlerMapping': no URL paths identified
02-May-2018 18:26:45 18:26:45.517 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'beanNameHandlerMapping': no URL paths identified
02-May-2018 18:26:45 18:26:45.517 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'resourceHandlerMapping': no URL paths identified
02-May-2018 18:26:45 18:26:45.517 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcResourceUrlProvider': no URL paths identified
02-May-2018 18:26:45 18:26:45.517 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'defaultServletHandlerMapping': no URL paths identified
02-May-2018 18:26:45 18:26:45.517 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'requestMappingHandlerAdapter': no URL paths identified
02-May-2018 18:26:45 18:26:45.517 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcConversionService': no URL paths identified
02-May-2018 18:26:45 18:26:45.518 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcValidator': no URL paths identified
02-May-2018 18:26:45 18:26:45.518 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcUriComponentsContributor': no URL paths identified
02-May-2018 18:26:45 18:26:45.518 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'httpRequestHandlerAdapter': no URL paths identified
02-May-2018 18:26:45 18:26:45.518 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'simpleControllerHandlerAdapter': no URL paths identified
02-May-2018 18:26:45 18:26:45.518 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'handlerExceptionResolver': no URL paths identified
02-May-2018 18:26:45 18:26:45.518 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcViewResolver': no URL paths identified
02-May-2018 18:26:45 18:26:45.518 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'mvcHandlerMappingIntrospector': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.autoconfigure.data.redis.LettuceConnectionConfiguration': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.autoconfigure.condition.BeanTypeRegistry': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'lettuceClientResources': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'redisConnectionFactory': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'redisTemplate': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'stringRedisTemplate': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'spring.redis-org.springframework.boot.autoconfigure.data.redis.RedisProperties': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.context.properties.ConfigurationPropertiesBindingPostProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.context.properties.ConfigurationBeanFactoryMetadata': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'counterRepository': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'counterController': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'counterService': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'entityLinksPluginRegistry': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'controllerEntityLinks': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'delegatingEntityLinks': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.hal.HalLinkDiscoverer#0': no URL paths identified
02-May-2018 18:26:45 18:26:45.519 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name '_halObjectMapper': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$DefaultObjectMapperCustomizer#0': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$Jackson2ModuleRegisteringBeanPostProcessor#0': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.hal.HalConfiguration#0': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name '_linkDiscovererRegistry': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.hateoas.LinkDiscoverers#0': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'defaultRelProvider': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'annotationRelProvider': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'relProviderPluginRegistry': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name '_relProvider': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.context.filter.TestTypeExcludeFilter': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.autoconfigure.properties.PropertyMappingContextCustomizer$PropertyMappingCheckBeanPostProcessor': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'environment': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'systemProperties': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'systemEnvironment': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'servletContext': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'contextParameters': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'contextAttributes': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'autoConfigurationReport': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.context.annotation.ConfigurationClassPostProcessor.importRegistry': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'org.springframework.boot.test.mock.mockito.MockitoBeans': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'messageSource': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.web.servlet.handler.BeanNameUrlHandlerMapping - Rejected bean name 'applicationEventMulticaster': no URL paths identified
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'beanNameHandlerMapping'
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'resourceHandlerMapping'
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'resourceHandlerMapping'
02-May-2018 18:26:45 18:26:45.520 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.521 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcContentNegotiationManager'
02-May-2018 18:26:45 18:26:45.521 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcUrlPathHelper'
02-May-2018 18:26:45 18:26:45.521 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'resourceHandlerMapping' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.522 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'resourceHandlerMapping'
02-May-2018 18:26:45 18:26:45.522 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcResourceUrlProvider'
02-May-2018 18:26:45 18:26:45.522 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'defaultServletHandlerMapping'
02-May-2018 18:26:45 18:26:45.522 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'defaultServletHandlerMapping'
02-May-2018 18:26:45 18:26:45.522 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.522 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'defaultServletHandlerMapping' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.523 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'defaultServletHandlerMapping'
02-May-2018 18:26:45 18:26:45.523 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'requestMappingHandlerAdapter'
02-May-2018 18:26:45 18:26:45.523 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'requestMappingHandlerAdapter'
02-May-2018 18:26:45 18:26:45.523 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.529 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcContentNegotiationManager'
02-May-2018 18:26:45 18:26:45.531 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean '_halObjectMapper'
02-May-2018 18:26:45 18:26:45.531 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '_halObjectMapper'
02-May-2018 18:26:45 18:26:45.536 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean '_halObjectMapper' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.538 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '_halObjectMapper'
02-May-2018 18:26:45 18:26:45.539 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcConversionService'
02-May-2018 18:26:45 18:26:45.540 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'mvcValidator'
02-May-2018 18:26:45 18:26:45.540 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'mvcValidator'
02-May-2018 18:26:45 18:26:45.540 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.540 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'mvcValidator' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.541 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'mvcValidator'
02-May-2018 18:26:45 18:26:45.542 [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.
02-May-2018 18:26:45 18:26:45.542 [main] DEBUG org.hibernate.validator.messageinterpolation.ResourceBundleMessageInterpolator - Loaded expression factory via original TCCL
02-May-2018 18:26:45 18:26:45.542 [main] DEBUG org.hibernate.validator.internal.engine.ConfigurationImpl - Setting custom MessageInterpolator of type org.springframework.validation.beanvalidation.LocaleContextMessageInterpolator
02-May-2018 18:26:45 18:26:45.542 [main] DEBUG org.hibernate.validator.internal.engine.ConfigurationImpl - Setting custom ConstraintValidatorFactory of type org.springframework.validation.beanvalidation.SpringConstraintValidatorFactory
02-May-2018 18:26:45 18:26:45.542 [main] DEBUG org.hibernate.validator.internal.engine.ConfigurationImpl - Setting custom ParameterNameProvider of type org.springframework.validation.beanvalidation.LocalValidatorFactoryBean$1
02-May-2018 18:26:45 18:26:45.542 [main] DEBUG org.hibernate.validator.internal.xml.ValidationXmlParser - Trying to load META-INF/validation.xml for XML based Validator configuration.
02-May-2018 18:26:45 18:26:45.542 [main] DEBUG org.hibernate.validator.internal.xml.ResourceLoaderHelper - Trying to load META-INF/validation.xml via user class loader
02-May-2018 18:26:45 18:26:45.543 [main] DEBUG org.hibernate.validator.internal.xml.ResourceLoaderHelper - Trying to load META-INF/validation.xml via TCCL
02-May-2018 18:26:45 18:26:45.543 [main] DEBUG org.hibernate.validator.internal.xml.ResourceLoaderHelper - Trying to load META-INF/validation.xml via Hibernate Validator's class loader
02-May-2018 18:26:45 18:26:45.543 [main] DEBUG org.hibernate.validator.internal.xml.ValidationXmlParser - No META-INF/validation.xml found. Using annotation based configuration only.
02-May-2018 18:26:45 18:26:45.547 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.springframework.validation.beanvalidation.LocaleContextMessageInterpolator as ValidatorFactory-scoped message interpolator.
02-May-2018 18:26:45 18:26:45.547 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.hibernate.validator.internal.engine.resolver.TraverseAllTraversableResolver as ValidatorFactory-scoped traversable resolver.
02-May-2018 18:26:45 18:26:45.547 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.hibernate.validator.internal.util.ExecutableParameterNameProvider as ValidatorFactory-scoped parameter name provider.
02-May-2018 18:26:45 18:26:45.547 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.hibernate.validator.internal.engine.DefaultClockProvider as ValidatorFactory-scoped clock provider.
02-May-2018 18:26:45 18:26:45.547 [main] DEBUG org.hibernate.validator.internal.engine.ValidatorFactoryImpl - HV000234: Using org.hibernate.validator.internal.engine.scripting.DefaultScriptEvaluatorFactory as ValidatorFactory-scoped script evaluator factory.
02-May-2018 18:26:45 18:26:45.552 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'mvcValidator'
02-May-2018 18:26:45 18:26:45.552 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'sortResolver'
02-May-2018 18:26:45 18:26:45.553 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'pageableResolver'
02-May-2018 18:26:45 18:26:45.553 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'pagedResourcesAssemblerArgumentResolver'
02-May-2018 18:26:45 18:26:45.554 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'requestMappingHandlerAdapter' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.558 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean '_relProvider'
02-May-2018 18:26:45 18:26:45.558 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '_relProvider'
02-May-2018 18:26:45 18:26:45.558 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '(inner bean)#18b74ea'
02-May-2018 18:26:45 18:26:45.559 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name '(inner bean)#18b74ea'
02-May-2018 18:26:45 18:26:45.559 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '(inner bean)#18b74ea'
02-May-2018 18:26:45 18:26:45.559 [main] DEBUG org.springframework.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is org.springframework.plugin.core.support.AbstractTypeAwareSupport$BeansOfTypeTargetSource@493da830
02-May-2018 18:26:45 18:26:45.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean '_relProvider' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '_relProvider'
02-May-2018 18:26:45 18:26:45.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_halObjectMapper'
02-May-2018 18:26:45 18:26:45.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'linkRelationMessageSource'
02-May-2018 18:26:45 18:26:45.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.hateoas.hal.HalConfiguration#0'
02-May-2018 18:26:45 18:26:45.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.hateoas.hal.HalConfiguration#0'
02-May-2018 18:26:45 18:26:45.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.hateoas.hal.HalConfiguration#0' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.562 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.hateoas.hal.HalConfiguration#0'
02-May-2018 18:26:45 18:26:45.563 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'requestMappingHandlerAdapter'
02-May-2018 18:26:45 18:26:45.563 [main] INFO org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter - Looking for @ControllerAdvice: org.springframework.web.context.support.GenericWebApplicationContext@6b63e6ad: startup date [Wed May 02 18:26:45 UTC 2018]; root of context hierarchy
02-May-2018 18:26:45 18:26:45.568 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'requestMappingHandlerAdapter'
02-May-2018 18:26:45 18:26:45.568 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcConversionService'
02-May-2018 18:26:45 18:26:45.568 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcValidator'
02-May-2018 18:26:45 18:26:45.568 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'mvcUriComponentsContributor'
02-May-2018 18:26:45 18:26:45.568 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'mvcUriComponentsContributor'
02-May-2018 18:26:45 18:26:45.568 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.569 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerAdapter'
02-May-2018 18:26:45 18:26:45.569 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcConversionService'
02-May-2018 18:26:45 18:26:45.569 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'mvcUriComponentsContributor' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.569 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'mvcUriComponentsContributor'
02-May-2018 18:26:45 18:26:45.569 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'httpRequestHandlerAdapter'
02-May-2018 18:26:45 18:26:45.569 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'httpRequestHandlerAdapter'
02-May-2018 18:26:45 18:26:45.570 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.570 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'httpRequestHandlerAdapter' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.570 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'httpRequestHandlerAdapter'
02-May-2018 18:26:45 18:26:45.570 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'simpleControllerHandlerAdapter'
02-May-2018 18:26:45 18:26:45.570 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'simpleControllerHandlerAdapter'
02-May-2018 18:26:45 18:26:45.570 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.571 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'simpleControllerHandlerAdapter' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.571 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'simpleControllerHandlerAdapter'
02-May-2018 18:26:45 18:26:45.572 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'handlerExceptionResolver'
02-May-2018 18:26:45 18:26:45.572 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'handlerExceptionResolver'
02-May-2018 18:26:45 18:26:45.572 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.574 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcContentNegotiationManager'
02-May-2018 18:26:45 18:26:45.574 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.ExceptionHandlerExceptionResolver - Looking for exception mappings: org.springframework.web.context.support.GenericWebApplicationContext@6b63e6ad: startup date [Wed May 02 18:26:45 UTC 2018]; root of context hierarchy
02-May-2018 18:26:45 18:26:45.575 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'handlerExceptionResolver' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.576 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'handlerExceptionResolver'
02-May-2018 18:26:45 18:26:45.576 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'mvcViewResolver'
02-May-2018 18:26:45 18:26:45.576 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'mvcViewResolver'
02-May-2018 18:26:45 18:26:45.576 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.web.servlet.config.annotation.DelegatingWebMvcConfiguration'
02-May-2018 18:26:45 18:26:45.576 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcContentNegotiationManager'
02-May-2018 18:26:45 18:26:45.576 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'mvcViewResolver' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.577 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'mvcViewResolver'
02-May-2018 18:26:45 18:26:45.577 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'mvcViewResolver'
02-May-2018 18:26:45 18:26:45.577 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.autoconfigure.data.redis.LettuceConnectionConfiguration'
02-May-2018 18:26:45 18:26:45.577 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.autoconfigure.condition.BeanTypeRegistry'
02-May-2018 18:26:45 18:26:45.577 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lettuceClientResources'
02-May-2018 18:26:45 18:26:45.577 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'redisConnectionFactory'
02-May-2018 18:26:45 18:26:45.577 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
02-May-2018 18:26:45 18:26:45.577 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
02-May-2018 18:26:45 18:26:45.578 [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
02-May-2018 18:26:45 18:26:45.580 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
02-May-2018 18:26:45 18:26:45.580 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'redisTemplate'
02-May-2018 18:26:45 18:26:45.580 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'redisTemplate'
02-May-2018 18:26:45 18:26:45.580 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
02-May-2018 18:26:45 18:26:45.581 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'redisConnectionFactory'
02-May-2018 18:26:45 18:26:45.581 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name 'redisTemplate' via factory method to bean named 'redisConnectionFactory'
02-May-2018 18:26:45 18:26:45.599 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'redisTemplate' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.609 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'redisTemplate'
02-May-2018 18:26:45 18:26:45.613 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'redisTemplate'
02-May-2018 18:26:45 18:26:45.614 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'stringRedisTemplate'
02-May-2018 18:26:45 18:26:45.614 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'stringRedisTemplate'
02-May-2018 18:26:45 18:26:45.614 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.autoconfigure.data.redis.RedisAutoConfiguration'
02-May-2018 18:26:45 18:26:45.614 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'redisConnectionFactory'
02-May-2018 18:26:45 18:26:45.614 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name 'stringRedisTemplate' via factory method to bean named 'redisConnectionFactory'
02-May-2018 18:26:45 18:26:45.615 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'stringRedisTemplate' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.617 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'stringRedisTemplate'
02-May-2018 18:26:45 18:26:45.618 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'stringRedisTemplate'
02-May-2018 18:26:45 18:26:45.618 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'spring.redis-org.springframework.boot.autoconfigure.data.redis.RedisProperties'
02-May-2018 18:26:45 18:26:45.618 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.context.properties.ConfigurationPropertiesBindingPostProcessor'
02-May-2018 18:26:45 18:26:45.618 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.context.properties.ConfigurationBeanFactoryMetadata'
02-May-2018 18:26:45 18:26:45.618 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'counterRepository'
02-May-2018 18:26:45 18:26:45.618 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'counterRepository'
02-May-2018 18:26:45 18:26:45.618 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterControllerTests.Config'
02-May-2018 18:26:45 18:26:45.626 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'counterRepository' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.628 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'counterRepository'
02-May-2018 18:26:45 18:26:45.628 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'counterController'
02-May-2018 18:26:45 18:26:45.628 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'counterController'
02-May-2018 18:26:45 18:26:45.628 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterControllerTests.Config'
02-May-2018 18:26:45 18:26:45.629 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterRepository'
02-May-2018 18:26:45 18:26:45.629 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name 'counterController' via factory method to bean named 'counterRepository'
02-May-2018 18:26:45 18:26:45.631 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'counterController' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.632 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'counterController'
02-May-2018 18:26:45 18:26:45.633 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'counterService'
02-May-2018 18:26:45 18:26:45.633 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'counterService'
02-May-2018 18:26:45 18:26:45.633 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterControllerTests.Config'
02-May-2018 18:26:45 18:26:45.633 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterRepository'
02-May-2018 18:26:45 18:26:45.633 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name 'counterService' via factory method to bean named 'counterRepository'
02-May-2018 18:26:45 18:26:45.634 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'counterService' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.636 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'counterService'
02-May-2018 18:26:45 18:26:45.637 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'entityLinksPluginRegistry'
02-May-2018 18:26:45 18:26:45.637 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'entityLinksPluginRegistry'
02-May-2018 18:26:45 18:26:45.637 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'entityLinksPluginRegistry' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.638 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'entityLinksPluginRegistry'
02-May-2018 18:26:45 18:26:45.638 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'entityLinksPluginRegistry'
02-May-2018 18:26:45 18:26:45.639 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'controllerEntityLinks'
02-May-2018 18:26:45 18:26:45.639 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'controllerEntityLinks'
02-May-2018 18:26:45 18:26:45.639 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'controllerEntityLinks' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.641 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '(inner bean)#999b951'
02-May-2018 18:26:45 18:26:45.643 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'pageableResolver'
02-May-2018 18:26:45 18:26:45.644 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'sortResolver'
02-May-2018 18:26:45 18:26:45.644 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name '(inner bean)#999b951' via property 'uriComponentsContributors' to bean named 'pageableResolver'
02-May-2018 18:26:45 18:26:45.644 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Autowiring by type from bean name '(inner bean)#999b951' via property 'uriComponentsContributors' to bean named 'sortResolver'
02-May-2018 18:26:45 18:26:45.645 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '(inner bean)#999b951'
02-May-2018 18:26:45 18:26:45.645 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'controllerEntityLinks'
02-May-2018 18:26:45 18:26:45.646 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'controllerEntityLinks'
02-May-2018 18:26:45 18:26:45.647 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'controllerEntityLinks'
02-May-2018 18:26:45 18:26:45.647 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'delegatingEntityLinks'
02-May-2018 18:26:45 18:26:45.647 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'delegatingEntityLinks'
02-May-2018 18:26:45 18:26:45.648 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '(inner bean)#10272bbb'
02-May-2018 18:26:45 18:26:45.648 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name '(inner bean)#10272bbb'
02-May-2018 18:26:45 18:26:45.649 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '(inner bean)#10272bbb'
02-May-2018 18:26:45 18:26:45.649 [main] DEBUG org.springframework.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is org.springframework.plugin.core.support.AbstractTypeAwareSupport$BeansOfTypeTargetSource@16736040
02-May-2018 18:26:45 18:26:45.650 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'delegatingEntityLinks' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.650 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'delegatingEntityLinks'
02-May-2018 18:26:45 18:26:45.650 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.hateoas.hal.HalLinkDiscoverer#0'
02-May-2018 18:26:45 18:26:45.651 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.hateoas.hal.HalLinkDiscoverer#0'
02-May-2018 18:26:45 18:26:45.651 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.hateoas.hal.HalLinkDiscoverer#0' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.651 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.hateoas.hal.HalLinkDiscoverer#0'
02-May-2018 18:26:45 18:26:45.651 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_halObjectMapper'
02-May-2018 18:26:45 18:26:45.651 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$DefaultObjectMapperCustomizer#0'
02-May-2018 18:26:45 18:26:45.652 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.hateoas.config.HypermediaSupportBeanDefinitionRegistrar$Jackson2ModuleRegisteringBeanPostProcessor#0'
02-May-2018 18:26:45 18:26:45.652 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.hateoas.hal.HalConfiguration#0'
02-May-2018 18:26:45 18:26:45.652 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean '_linkDiscovererRegistry'
02-May-2018 18:26:45 18:26:45.652 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean '_linkDiscovererRegistry'
02-May-2018 18:26:45 18:26:45.652 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean '_linkDiscovererRegistry' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.653 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name '_linkDiscovererRegistry'
02-May-2018 18:26:45 18:26:45.653 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean '_linkDiscovererRegistry'
02-May-2018 18:26:45 18:26:45.653 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'org.springframework.hateoas.LinkDiscoverers#0'
02-May-2018 18:26:45 18:26:45.653 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.hateoas.LinkDiscoverers#0'
02-May-2018 18:26:45 18:26:45.653 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_linkDiscovererRegistry'
02-May-2018 18:26:45 18:26:45.653 [main] DEBUG org.springframework.aop.framework.JdkDynamicAopProxy - Creating JDK dynamic proxy: target source is org.springframework.plugin.core.support.AbstractTypeAwareSupport$BeansOfTypeTargetSource@26ca61bf
02-May-2018 18:26:45 18:26:45.654 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'org.springframework.hateoas.LinkDiscoverers#0' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.655 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.hateoas.LinkDiscoverers#0'
02-May-2018 18:26:45 18:26:45.655 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'defaultRelProvider'
02-May-2018 18:26:45 18:26:45.655 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'defaultRelProvider'
02-May-2018 18:26:45 18:26:45.655 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'defaultRelProvider' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.656 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'defaultRelProvider'
02-May-2018 18:26:45 18:26:45.656 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'annotationRelProvider'
02-May-2018 18:26:45 18:26:45.656 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'annotationRelProvider'
02-May-2018 18:26:45 18:26:45.656 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'annotationRelProvider' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.656 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'annotationRelProvider'
02-May-2018 18:26:45 18:26:45.657 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating shared instance of singleton bean 'relProviderPluginRegistry'
02-May-2018 18:26:45 18:26:45.657 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'relProviderPluginRegistry'
02-May-2018 18:26:45 18:26:45.657 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Eagerly caching bean 'relProviderPluginRegistry' to allow for resolving potential circular references
02-May-2018 18:26:45 18:26:45.657 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Invoking afterPropertiesSet() on bean with name 'relProviderPluginRegistry'
02-May-2018 18:26:45 18:26:45.658 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'relProviderPluginRegistry'
02-May-2018 18:26:45 18:26:45.658 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_relProvider'
02-May-2018 18:26:45 18:26:45.658 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.context.event.internalEventListenerFactory'
02-May-2018 18:26:45 18:26:45.668 [main] DEBUG org.springframework.web.context.support.GenericWebApplicationContext - Unable to locate LifecycleProcessor with name 'lifecycleProcessor': using default [org.springframework.context.support.DefaultLifecycleProcessor@483b0690]
02-May-2018 18:26:45 18:26:45.668 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'
02-May-2018 18:26:45 18:26:45.669 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcResourceUrlProvider'
02-May-2018 18:26:45 18:26:45.669 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'entityLinksPluginRegistry'
02-May-2018 18:26:45 18:26:45.669 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean '_linkDiscovererRegistry'
02-May-2018 18:26:45 18:26:45.669 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'relProviderPluginRegistry'
02-May-2018 18:26:45 18:26:45.669 [main] DEBUG org.springframework.web.servlet.resource.ResourceUrlProvider - Looking for resource handler mappings
02-May-2018 18:26:45 18:26:45.670 [main] DEBUG org.springframework.web.servlet.resource.ResourceUrlProvider - No resource handling mappings found
02-May-2018 18:26:45 18:26:45.670 [main] DEBUG org.springframework.core.env.PropertySourcesPropertyResolver - Could not find key 'spring.liveBeansView.mbeanDomain' in any property source
02-May-2018 18:26:45 18:26:45.670 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Storing ApplicationContext in cache under key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:45 18:26:45.671 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 22, missCount = 3]
02-May-2018 18:26:45 18:26:45.671 [main] DEBUG org.springframework.test.context.web.ServletTestExecutionListener - Setting up MockHttpServletRequest, MockHttpServletResponse, ServletWebRequest, and RequestContextHolder for test context [DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@6870c3c2, testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map[[empty]]].
02-May-2018 18:26:45 18:26:45.671 [main] DEBUG org.springframework.test.context.support.DependencyInjectionTestExecutionListener - Performing dependency injection for test context [[DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@6870c3c2, testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], 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]]].
02-May-2018 18:26:45 18:26:45.672 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:45 18:26:45.672 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 23, missCount = 3]
02-May-2018 18:26:45 18:26:45.674 [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
02-May-2018 18:26:45 18:26:45.674 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation interface org.springframework.beans.factory.annotation.Autowired: java.lang.NullPointerException
02-May-2018 18:26:45 18:26:45.674 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterRepository'
02-May-2018 18:26:45 18:26:45.674 [main] DEBUG org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor - Autowiring by type from bean name 'org.springframework.analytics.rest.controller.CounterControllerTests' to bean named 'counterRepository'
02-May-2018 18:26:45 18:26:45.675 [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
02-May-2018 18:26:45 18:26:45.675 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation interface org.springframework.beans.factory.annotation.Autowired: java.lang.NullPointerException
02-May-2018 18:26:45 18:26:45.675 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterService'
02-May-2018 18:26:45 18:26:45.675 [main] DEBUG org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor - Autowiring by type from bean name 'org.springframework.analytics.rest.controller.CounterControllerTests' to bean named 'counterService'
02-May-2018 18:26:45 18:26:45.675 [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
02-May-2018 18:26:45 18:26:45.676 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation interface org.springframework.beans.factory.annotation.Autowired: java.lang.NullPointerException
02-May-2018 18:26:45 18:26:45.676 [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@6b63e6ad'
02-May-2018 18:26:45 18:26:45.677 [main] DEBUG io.lettuce.core.RedisClient - Trying to get a Redis connection for: RedisURI [host='localhost', port=6379]
02-May-2018 18:26:45 18:26:45.678 [main] DEBUG io.lettuce.core.resource.DefaultEventLoopGroupProvider - Adding reference to io.netty.channel.nio.NioEventLoopGroup@cedee22, existing ref count 0
02-May-2018 18:26:45 18:26:45.678 [main] DEBUG io.lettuce.core.RedisClient - Resolved SocketAddress localhost:6379 using RedisURI [host='localhost', port=6379]
02-May-2018 18:26:45 18:26:45.678 [main] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379
02-May-2018 18:26:45 18:26:45.682 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x2d33a34d, [id: 0xd1fb899e] (inactive), chid=0x3] channelRegistered()
02-May-2018 18:26:45 18:26:45.683 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, chid=0x3] channelActive()
02-May-2018 18:26:45 18:26:45.686 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, epid=0x3] activateEndpointAndExecuteBufferedCommands 0 command(s) buffered
02-May-2018 18:26:45 18:26:45.686 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, epid=0x3] activating endpoint
02-May-2018 18:26:45 18:26:45.686 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, epid=0x3] flushCommands()
02-May-2018 18:26:45 18:26:45.686 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, epid=0x3] flushCommands() Flushing 0 commands
02-May-2018 18:26:45 18:26:45.686 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
02-May-2018 18:26:45 18:26:45.688 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
02-May-2018 18:26:45 18:26:45.688 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, chid=0x3] channelActive() done
02-May-2018 18:26:45 18:26:45.688 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379: Success
02-May-2018 18:26:45 18:26:45.688 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@72612f60)
02-May-2018 18:26:45 18:26:45.688 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@72612f60)
02-May-2018 18:26:45 18:26:45.689 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - Before test method: context [DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@6870c3c2, testMethod = testList@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], 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].
02-May-2018 18:26:45 18:26:45.689 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:45 18:26:45.690 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 24, missCount = 3]
02-May-2018 18:26:45 18:26:45.690 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.test.mock.mockito.MockitoBeans'
02-May-2018 18:26:45 18:26:45.691 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Initializing servlet ''
02-May-2018 18:26:45 18:26:45.691 [main] INFO org.springframework.mock.web.MockServletContext - Initializing Spring FrameworkServlet ''
02-May-2018 18:26:45 18:26:45.691 [main] INFO org.springframework.test.web.servlet.TestDispatcherServlet - FrameworkServlet '': initialization started
02-May-2018 18:26:45 18:26:45.691 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate MultipartResolver with name 'multipartResolver': no multipart request handling provided
02-May-2018 18:26:45 18:26:45.691 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
02-May-2018 18:26:45 18:26:45.692 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
02-May-2018 18:26:45 18:26:45.692 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate LocaleResolver with name 'localeResolver': using default [org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver@59b32539]
02-May-2018 18:26:45 18:26:45.692 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
02-May-2018 18:26:45 18:26:45.693 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
02-May-2018 18:26:45 18:26:45.693 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate ThemeResolver with name 'themeResolver': using default [org.springframework.web.servlet.theme.FixedThemeResolver@5b47731f]
02-May-2018 18:26:45 18:26:45.693 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerMapping'
02-May-2018 18:26:45 18:26:45.693 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'viewControllerHandlerMapping'
02-May-2018 18:26:45 18:26:45.693 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'beanNameHandlerMapping'
02-May-2018 18:26:45 18:26:45.694 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'resourceHandlerMapping'
02-May-2018 18:26:45 18:26:45.694 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'defaultServletHandlerMapping'
02-May-2018 18:26:45 18:26:45.694 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerAdapter'
02-May-2018 18:26:45 18:26:45.694 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'httpRequestHandlerAdapter'
02-May-2018 18:26:45 18:26:45.694 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'simpleControllerHandlerAdapter'
02-May-2018 18:26:45 18:26:45.695 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'handlerExceptionResolver'
02-May-2018 18:26:45 18:26:45.695 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
02-May-2018 18:26:45 18:26:45.695 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
02-May-2018 18:26:45 18:26:45.696 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate RequestToViewNameTranslator with name 'viewNameTranslator': using default [org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator@233db8e9]
02-May-2018 18:26:45 18:26:45.696 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcViewResolver'
02-May-2018 18:26:45 18:26:45.696 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
02-May-2018 18:26:45 18:26:45.697 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
02-May-2018 18:26:45 18:26:45.697 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate FlashMapManager with name 'flashMapManager': using default [org.springframework.web.servlet.support.SessionFlashMapManager@40c2ce52]
02-May-2018 18:26:45 18:26:45.697 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Published WebApplicationContext of servlet '' as ServletContext attribute with name [org.springframework.web.servlet.FrameworkServlet.CONTEXT.]
02-May-2018 18:26:45 18:26:45.697 [main] INFO org.springframework.test.web.servlet.TestDispatcherServlet - FrameworkServlet '': initialization completed in 6 ms
02-May-2018 18:26:45 18:26:45.697 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Servlet '' configured successfully
02-May-2018 18:26:45 18:26:45.702 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.702 [main] DEBUG io.lettuce.core.RedisClient - Trying to get a Redis connection for: RedisURI [host='localhost', port=6379]
02-May-2018 18:26:45 18:26:45.703 [main] DEBUG io.lettuce.core.resource.DefaultEventLoopGroupProvider - Adding reference to io.netty.channel.nio.NioEventLoopGroup@7980cf2c, existing ref count 0
02-May-2018 18:26:45 18:26:45.703 [main] DEBUG io.lettuce.core.RedisClient - Resolved SocketAddress localhost:6379 using RedisURI [host='localhost', port=6379]
02-May-2018 18:26:45 18:26:45.703 [main] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379
02-May-2018 18:26:45 18:26:45.708 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, [id: 0x5b7f42f1] (inactive), chid=0x4] channelRegistered()
02-May-2018 18:26:45 18:26:45.709 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] channelActive()
02-May-2018 18:26:45 18:26:45.709 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] activateEndpointAndExecuteBufferedCommands 0 command(s) buffered
02-May-2018 18:26:45 18:26:45.709 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] activating endpoint
02-May-2018 18:26:45 18:26:45.709 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] flushCommands()
02-May-2018 18:26:45 18:26:45.709 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] flushCommands() Flushing 0 commands
02-May-2018 18:26:45 18:26:45.709 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
02-May-2018 18:26:45 18:26:45.709 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
02-May-2018 18:26:45 18:26:45.709 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] channelActive() done
02-May-2018 18:26:45 18:26:45.710 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379: Success
02-May-2018 18:26:45 18:26:45.710 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@105a06d6)
02-May-2018 18:26:45 18:26:45.710 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@105a06d6)
02-May-2018 18:26:45 18:26:45.725 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.725 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.727 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.728 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.734 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.738 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.738 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.738 [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]
02-May-2018 18:26:45 18:26:45.741 [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
02-May-2018 18:26:45 18:26:45.754 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.754 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.754 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.754 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.755 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.755 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.756 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.756 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.756 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.756 [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]
02-May-2018 18:26:45 18:26:45.756 [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
02-May-2018 18:26:45 18:26:45.757 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.758 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.761 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.761 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.761 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.761 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.762 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.766 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.766 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.766 [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]
02-May-2018 18:26:45 18:26:45.766 [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
02-May-2018 18:26:45 18:26:45.772 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.772 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.772 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.772 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.772 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.772 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.773 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.774 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.774 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.774 [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]
02-May-2018 18:26:45 18:26:45.774 [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
02-May-2018 18:26:45 18:26:45.774 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.774 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.774 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.774 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.775 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.775 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.778 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.779 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.779 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.779 [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]
02-May-2018 18:26:45 18:26:45.779 [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
02-May-2018 18:26:45 18:26:45.780 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.780 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.780 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.780 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.780 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.782 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.782 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.783 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.783 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.783 [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]
02-May-2018 18:26:45 18:26:45.783 [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
02-May-2018 18:26:45 18:26:45.783 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.784 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - DispatcherServlet with name '' processing GET request for [/metrics/counters]
02-May-2018 18:26:45 18:26:45.784 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Looking up handler method for path /metrics/counters
02-May-2018 18:26:45 18:26:45.784 [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)]
02-May-2018 18:26:45 18:26:45.785 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterController'
02-May-2018 18:26:45 18:26:45.785 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Last-Modified value for [/metrics/counters] is: -1
02-May-2018 18:26:45 18:26:45.792 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.794 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.794 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.794 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.794 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.795 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.795 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 70 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.795 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.795 [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]
02-May-2018 18:26:45 18:26:45.796 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZRANGE, output=ValueListOutput [output=[[B@4306bf0b, [B@21fb482d], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
02-May-2018 18:26:45 18:26:45.797 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.798 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.798 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.799 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.799 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.799 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.801 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.801 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 44 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.801 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.801 [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]
02-May-2018 18:26:45 18:26:45.802 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=MGET, output=KeyValueListOutput [output=[KeyValue[[B@38dd782c, [B@439ce53a], KeyValue[[B@7b8cd04a, [B@1efc7289]], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
02-May-2018 18:26:45 18:26:45.802 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.803 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.803 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.803 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.803 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.803 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.804 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.804 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.804 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.804 [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]
02-May-2018 18:26:45 18:26:45.805 [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
02-May-2018 18:26:45 18:26:45.807 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.807 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.807 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.807 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.807 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.808 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.808 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.809 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.809 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.809 [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]
02-May-2018 18:26:45 18:26:45.809 [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
02-May-2018 18:26:45 18:26:45.810 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.826 [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@448b808a]
02-May-2018 18:26:45 18:26:45.826 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Null ModelAndView returned to DispatcherServlet with name '': assuming HandlerAdapter completed request handling
02-May-2018 18:26:45 18:26:45.826 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Successfully completed request
02-May-2018 18:26:45 18:26:45.827 [main] DEBUG com.jayway.jsonpath.internal.path.CompiledPath - Evaluating path: $['page']['totalElements']
02-May-2018 18:26:45 18:26:45.827 [main] DEBUG com.jayway.jsonpath.internal.path.CompiledPath - Evaluating path: $['content'][*]['name']
02-May-2018 18:26:45 18:26:45.827 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.827 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.827 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.828 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.828 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.829 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 70 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.829 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.829 [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]
02-May-2018 18:26:45 18:26:45.829 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=ZRANGE, output=ValueListOutput [output=[[B@4d336562, [B@69a2eccb], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
02-May-2018 18:26:45 18:26:45.829 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.829 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.830 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.830 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.830 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.830 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.830 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.831 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=MGET, output=KeyValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.831 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 44 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.831 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.831 [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]
02-May-2018 18:26:45 18:26:45.831 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=MGET, output=KeyValueListOutput [output=[KeyValue[[B@74316f35, [B@9242300], KeyValue[[B@5fea5f33, [B@215024b1]], error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
02-May-2018 18:26:45 18:26:45.832 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.832 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.832 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.832 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.832 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.832 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.833 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.833 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.833 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.833 [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]
02-May-2018 18:26:45 18:26:45.833 [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
02-May-2018 18:26:45 18:26:45.834 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.834 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.834 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.834 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.834 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.834 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.835 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.835 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.835 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.835 [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]
02-May-2018 18:26:45 18:26:45.835 [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
02-May-2018 18:26:45 18:26:45.836 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.836 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.837 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.837 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.837 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.844 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.844 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.844 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.844 [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]
02-May-2018 18:26:45 18:26:45.845 [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
02-May-2018 18:26:45 18:26:45.837 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.845 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.846 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.848 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.848 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.848 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.848 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.848 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.849 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.849 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.849 [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]
02-May-2018 18:26:45 18:26:45.849 [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
02-May-2018 18:26:45 18:26:45.850 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.850 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.850 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.850 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.850 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.850 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.851 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.852 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.852 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.852 [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]
02-May-2018 18:26:45 18:26:45.852 [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
02-May-2018 18:26:45 18:26:45.852 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.853 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:45 18:26:45.853 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.853 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:45 18:26:45.853 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:45 18:26:45.853 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:45 18:26:45.854 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:45 18:26:45.854 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
02-May-2018 18:26:45 18:26:45.854 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:45 18:26:45.854 [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]
02-May-2018 18:26:45 18:26:45.854 [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
02-May-2018 18:26:45 18:26:45.854 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:45 18:26:45.855 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:45 18:26:45.855 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 25, missCount = 3]
02-May-2018 18:26:45 18:26:45.855 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:45 18:26:45.855 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 26, missCount = 3]
02-May-2018 18:26:45 18:26:45.856 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:45 18:26:45.856 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 27, missCount = 3]
02-May-2018 18:26:45 18:26:45.856 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.test.mock.mockito.MockitoBeans'
02-May-2018 18:26:45 18:26:45.857 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - After test method: context [DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@6870c3c2, testMethod = testList@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], 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].
02-May-2018 18:26:45 18:26:45.857 [main] DEBUG org.springframework.test.context.web.ServletTestExecutionListener - Resetting RequestContextHolder for test context [DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@6870c3c2, testMethod = testList@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], 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]].
02-May-2018 18:26:45 18:26:45.857 [main] DEBUG io.lettuce.core.RedisChannelHandler - close()
02-May-2018 18:26:45 18:26:45.857 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, epid=0x3] close()
02-May-2018 18:26:45 18:26:45.862 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, chid=0x3] channelInactive()
02-May-2018 18:26:45 18:26:45.862 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, epid=0x3] deactivating endpoint handler
02-May-2018 18:26:45 18:26:45.862 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, chid=0x3] channelInactive() done
02-May-2018 18:26:45 18:26:45.862 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
02-May-2018 18:26:45 18:26:45.862 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
02-May-2018 18:26:45 18:26:45.862 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
02-May-2018 18:26:45 18:26:45.862 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
02-May-2018 18:26:45 18:26:45.862 [lettuce-nioEventLoop-12-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x2d33a34d, /127.0.0.1:49356 -> localhost/127.0.0.1:6379, chid=0x3] channelUnregistered()
02-May-2018 18:26:46 18:26:46.966 [lettuce-nioEventLoop-12-1] DEBUG io.netty.buffer.PoolThreadCache - Freed 1 thread-local buffer(s) from thread: lettuce-nioEventLoop-12-1
02-May-2018 18:26:46 18:26:46.970 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [org.springframework.analytics.rest.controller.CounterControllerTests]
02-May-2018 18:26:46 18:26:46.970 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [org.springframework.analytics.rest.controller.CounterControllerTests]
02-May-2018 18:26:46 18:26:46.970 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:46 18:26:46.970 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 28, missCount = 3]
02-May-2018 18:26:46 18:26:46.970 [main] DEBUG org.springframework.test.context.web.ServletTestExecutionListener - Setting up MockHttpServletRequest, MockHttpServletResponse, ServletWebRequest, and RequestContextHolder for test context [DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@ebda593, testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]], attributes = map['org.springframework.test.context.support.DependencyInjectionTestExecutionListener.reinjectDependencies' -> true]].
02-May-2018 18:26:46 18:26:46.971 [main] DEBUG org.springframework.test.context.support.DependencyInjectionTestExecutionListener - Performing dependency injection for test context [[DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@ebda593, testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], 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]]].
02-May-2018 18:26:46 18:26:46.971 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:46 18:26:46.971 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 29, missCount = 3]
02-May-2018 18:26:46 18:26:46.971 [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
02-May-2018 18:26:46 18:26:46.971 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation interface org.springframework.beans.factory.annotation.Autowired: java.lang.NullPointerException
02-May-2018 18:26:46 18:26:46.971 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterRepository'
02-May-2018 18:26:46 18:26:46.971 [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
02-May-2018 18:26:46 18:26:46.971 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation interface org.springframework.beans.factory.annotation.Autowired: java.lang.NullPointerException
02-May-2018 18:26:46 18:26:46.971 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterService'
02-May-2018 18:26:46 18:26:46.971 [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
02-May-2018 18:26:46 18:26:46.971 [main] DEBUG org.springframework.core.annotation.AnnotationUtils - Failed to meta-introspect annotation interface org.springframework.beans.factory.annotation.Autowired: java.lang.NullPointerException
02-May-2018 18:26:46 18:26:46.973 [main] DEBUG io.lettuce.core.RedisClient - Trying to get a Redis connection for: RedisURI [host='localhost', port=6379]
02-May-2018 18:26:46 18:26:46.973 [main] DEBUG io.lettuce.core.resource.DefaultEventLoopGroupProvider - Adding reference to io.netty.channel.nio.NioEventLoopGroup@310ed6b4, existing ref count 0
02-May-2018 18:26:46 18:26:46.973 [main] DEBUG io.lettuce.core.RedisClient - Resolved SocketAddress localhost:6379 using RedisURI [host='localhost', port=6379]
02-May-2018 18:26:46 18:26:46.974 [main] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379
02-May-2018 18:26:46 18:26:46.981 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x05b0e31f, [id: 0xe22eed85] (inactive), chid=0x5] channelRegistered()
02-May-2018 18:26:46 18:26:46.982 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, chid=0x5] channelActive()
02-May-2018 18:26:46 18:26:46.982 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, epid=0x5] activateEndpointAndExecuteBufferedCommands 0 command(s) buffered
02-May-2018 18:26:46 18:26:46.982 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, epid=0x5] activating endpoint
02-May-2018 18:26:46 18:26:46.982 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, epid=0x5] flushCommands()
02-May-2018 18:26:46 18:26:46.982 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, epid=0x5] flushCommands() Flushing 0 commands
02-May-2018 18:26:46 18:26:46.983 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
02-May-2018 18:26:46 18:26:46.983 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelActive()
02-May-2018 18:26:46 18:26:46.983 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, chid=0x5] channelActive() done
02-May-2018 18:26:46 18:26:46.983 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.RedisClient - Connecting to Redis at localhost:6379: Success
02-May-2018 18:26:46 18:26:46.983 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@2a225ffc)
02-May-2018 18:26:46 18:26:46.983 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] userEventTriggered(ctx, io.lettuce.core.ConnectionEvents$Activated@2a225ffc)
02-May-2018 18:26:46 18:26:46.984 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - Before test method: context [DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@ebda593, testMethod = testGetAndDelete@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], 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].
02-May-2018 18:26:46 18:26:46.984 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:46 18:26:46.984 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 30, missCount = 3]
02-May-2018 18:26:46 18:26:46.985 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.test.mock.mockito.MockitoBeans'
02-May-2018 18:26:46 18:26:46.986 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Initializing servlet ''
02-May-2018 18:26:46 18:26:46.986 [main] INFO org.springframework.mock.web.MockServletContext - Initializing Spring FrameworkServlet ''
02-May-2018 18:26:46 18:26:46.986 [main] INFO org.springframework.test.web.servlet.TestDispatcherServlet - FrameworkServlet '': initialization started
02-May-2018 18:26:46 18:26:46.986 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate MultipartResolver with name 'multipartResolver': no multipart request handling provided
02-May-2018 18:26:46 18:26:46.986 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
02-May-2018 18:26:46 18:26:46.987 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver'
02-May-2018 18:26:46 18:26:46.987 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate LocaleResolver with name 'localeResolver': using default [org.springframework.web.servlet.i18n.AcceptHeaderLocaleResolver@640cc04b]
02-May-2018 18:26:46 18:26:46.987 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
02-May-2018 18:26:46 18:26:46.987 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.theme.FixedThemeResolver'
02-May-2018 18:26:46 18:26:46.987 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate ThemeResolver with name 'themeResolver': using default [org.springframework.web.servlet.theme.FixedThemeResolver@630bf683]
02-May-2018 18:26:46 18:26:46.988 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerMapping'
02-May-2018 18:26:46 18:26:46.988 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'viewControllerHandlerMapping'
02-May-2018 18:26:46 18:26:46.988 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'beanNameHandlerMapping'
02-May-2018 18:26:46 18:26:46.988 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'resourceHandlerMapping'
02-May-2018 18:26:46 18:26:46.988 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'defaultServletHandlerMapping'
02-May-2018 18:26:46 18:26:46.988 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'requestMappingHandlerAdapter'
02-May-2018 18:26:46 18:26:46.988 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'httpRequestHandlerAdapter'
02-May-2018 18:26:46 18:26:46.988 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'simpleControllerHandlerAdapter'
02-May-2018 18:26:46 18:26:46.989 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'handlerExceptionResolver'
02-May-2018 18:26:46 18:26:46.989 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
02-May-2018 18:26:46 18:26:46.989 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator'
02-May-2018 18:26:46 18:26:46.989 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate RequestToViewNameTranslator with name 'viewNameTranslator': using default [org.springframework.web.servlet.view.DefaultRequestToViewNameTranslator@22d322f5]
02-May-2018 18:26:46 18:26:46.989 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'mvcViewResolver'
02-May-2018 18:26:46 18:26:46.990 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
02-May-2018 18:26:46 18:26:46.990 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Finished creating instance of bean 'org.springframework.web.servlet.support.SessionFlashMapManager'
02-May-2018 18:26:46 18:26:46.990 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Unable to locate FlashMapManager with name 'flashMapManager': using default [org.springframework.web.servlet.support.SessionFlashMapManager@65afeb6d]
02-May-2018 18:26:46 18:26:46.990 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Published WebApplicationContext of servlet '' as ServletContext attribute with name [org.springframework.web.servlet.FrameworkServlet.CONTEXT.]
02-May-2018 18:26:46 18:26:46.990 [main] INFO org.springframework.test.web.servlet.TestDispatcherServlet - FrameworkServlet '': initialization completed in 4 ms
02-May-2018 18:26:46 18:26:46.990 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Servlet '' configured successfully
02-May-2018 18:26:46 18:26:46.991 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:46 18:26:46.991 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:46 18:26:46.991 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:46 18:26:46.991 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:46 18:26:46.992 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:46 18:26:46.992 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:46 18:26:46.992 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:46 18:26:46.993 [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]
02-May-2018 18:26:46 18:26:46.993 [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
02-May-2018 18:26:46 18:26:46.994 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:46 18:26:46.994 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:46 18:26:46.994 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:46 18:26:46.994 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:46 18:26:46.994 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:46 18:26:46.995 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:46 18:26:46.995 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:46 18:26:46.995 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:46 18:26:46.996 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:46 18:26:46.996 [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]
02-May-2018 18:26:46 18:26:46.996 [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
02-May-2018 18:26:46 18:26:46.997 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:46 18:26:46.997 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:46 18:26:46.997 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:46 18:26:46.997 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:46 18:26:46.997 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:46 18:26:46.997 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:46 18:26:46.998 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:46 18:26:46.998 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
02-May-2018 18:26:46 18:26:46.998 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:46 18:26:46.998 [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]
02-May-2018 18:26:46 18:26:46.998 [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
02-May-2018 18:26:46 18:26:46.999 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:46 18:26:46.999 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:46 18:26:46.999 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:46.999 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.000 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.000 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.000 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.000 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.000 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.000 [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]
02-May-2018 18:26:47 18:26:47.000 [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
02-May-2018 18:26:47 18:26:47.001 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.001 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.001 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.001 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.001 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.002 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.002 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZINCRBY, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.002 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.002 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.002 [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]
02-May-2018 18:26:47 18:26:47.002 [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
02-May-2018 18:26:47 18:26:47.003 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.003 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.004 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.004 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.004 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.004 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.005 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=SET, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.005 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.005 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.005 [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]
02-May-2018 18:26:47 18:26:47.005 [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
02-May-2018 18:26:47 18:26:47.006 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.006 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.006 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - DispatcherServlet with name '' processing GET request for [/metrics/counters/foo]
02-May-2018 18:26:47 18:26:47.006 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Looking up handler method for path /metrics/counters/foo
02-May-2018 18:26:47 18:26:47.007 [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)]
02-May-2018 18:26:47 18:26:47.007 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterController'
02-May-2018 18:26:47 18:26:47.007 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Last-Modified value for [/metrics/counters/foo] is: -1
02-May-2018 18:26:47 18:26:47.008 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.009 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.009 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.009 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.009 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.010 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 20 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.010 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.010 [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]
02-May-2018 18:26:47 18:26:47.010 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=GET, output=ValueOutput [output=[B@7bed71ac, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
02-May-2018 18:26:47 18:26:47.011 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.011 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.011 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.011 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.011 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.011 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.012 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.012 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.012 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.012 [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]
02-May-2018 18:26:47 18:26:47.012 [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
02-May-2018 18:26:47 18:26:47.013 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.013 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.016 [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@448b808a]
02-May-2018 18:26:47 18:26:47.017 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Null ModelAndView returned to DispatcherServlet with name '': assuming HandlerAdapter completed request handling
02-May-2018 18:26:47 18:26:47.017 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Successfully completed request
02-May-2018 18:26:47 18:26:47.017 [main] DEBUG com.jayway.jsonpath.internal.path.CompiledPath - Evaluating path: $['name']
02-May-2018 18:26:47 18:26:47.017 [main] DEBUG com.jayway.jsonpath.internal.path.CompiledPath - Evaluating path: $['value']
02-May-2018 18:26:47 18:26:47.018 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - DispatcherServlet with name '' processing DELETE request for [/metrics/counters/foo]
02-May-2018 18:26:47 18:26:47.018 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Looking up handler method for path /metrics/counters/foo
02-May-2018 18:26:47 18:26:47.018 [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)]
02-May-2018 18:26:47 18:26:47.018 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterController'
02-May-2018 18:26:47 18:26:47.019 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.019 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.019 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.019 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.020 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.020 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 20 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.020 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.020 [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]
02-May-2018 18:26:47 18:26:47.020 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.RedisStateMachine - Decoded LatencyMeteredCommand [type=GET, output=ValueOutput [output=[B@5b38b7cc, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand], empty stack: true
02-May-2018 18:26:47 18:26:47.021 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.021 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.021 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.021 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.021 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.021 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.022 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.022 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 7 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.022 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.022 [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]
02-May-2018 18:26:47 18:26:47.022 [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
02-May-2018 18:26:47 18:26:47.023 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.023 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.023 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.023 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.024 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.024 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.024 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZREM, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.025 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.025 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.025 [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]
02-May-2018 18:26:47 18:26:47.025 [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
02-May-2018 18:26:47 18:26:47.026 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.026 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.026 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.026 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.026 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.026 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.027 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=DEL, output=IntegerOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.027 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.027 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.027 [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]
02-May-2018 18:26:47 18:26:47.027 [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
02-May-2018 18:26:47 18:26:47.028 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.028 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.028 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Null ModelAndView returned to DispatcherServlet with name '': assuming HandlerAdapter completed request handling
02-May-2018 18:26:47 18:26:47.028 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Successfully completed request
02-May-2018 18:26:47 18:26:47.029 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - DispatcherServlet with name '' processing GET request for [/metrics/counters/foo]
02-May-2018 18:26:47 18:26:47.029 [main] DEBUG org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - Looking up handler method for path /metrics/counters/foo
02-May-2018 18:26:47 18:26:47.029 [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)]
02-May-2018 18:26:47 18:26:47.029 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'counterController'
02-May-2018 18:26:47 18:26:47.029 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Last-Modified value for [/metrics/counters/foo] is: -1
02-May-2018 18:26:47 18:26:47.030 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.030 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.030 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.030 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.030 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=GET, output=ValueOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.031 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.031 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.031 [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]
02-May-2018 18:26:47 18:26:47.031 [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
02-May-2018 18:26:47 18:26:47.031 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.031 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.031 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.032 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.032 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.032 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.032 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZSCORE, output=DoubleOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.032 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 5 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.033 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.033 [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]
02-May-2018 18:26:47 18:26:47.033 [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
02-May-2018 18:26:47 18:26:47.033 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.033 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.033 [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
02-May-2018 18:26:47 18:26:47.034 [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
02-May-2018 18:26:47 18:26:47.034 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Null ModelAndView returned to DispatcherServlet with name '': assuming HandlerAdapter completed request handling
02-May-2018 18:26:47 18:26:47.034 [main] DEBUG org.springframework.test.web.servlet.TestDispatcherServlet - Successfully completed request
02-May-2018 18:26:47 18:26:47.035 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Opening RedisConnection
02-May-2018 18:26:47 18:26:47.035 [main] DEBUG io.lettuce.core.RedisChannelHandler - dispatching command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.035 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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]
02-May-2018 18:26:47 18:26:47.035 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> 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)
02-May-2018 18:26:47 18:26:47.035 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandEncoder - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=ZRANGE, output=ValueListOutput [output=[], error='null'], commandType=io.lettuce.core.protocol.Command]
02-May-2018 18:26:47 18:26:47.036 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Received: 4 bytes, 1 commands in the stack
02-May-2018 18:26:47 18:26:47.036 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] Stack contains: 1 commands
02-May-2018 18:26:47 18:26:47.036 [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]
02-May-2018 18:26:47 18:26:47.036 [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
02-May-2018 18:26:47 18:26:47.036 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] write() done
02-May-2018 18:26:47 18:26:47.037 [main] DEBUG org.springframework.data.redis.core.RedisConnectionUtils - Closing Redis Connection
02-May-2018 18:26:47 18:26:47.037 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:47 18:26:47.037 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 31, missCount = 3]
02-May-2018 18:26:47 18:26:47.037 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:47 18:26:47.037 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 32, missCount = 3]
02-May-2018 18:26:47 18:26:47.038 [main] DEBUG org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate - Retrieved ApplicationContext from cache with key [[WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.test.context.web.WebDelegatingSmartContextLoader', parent = [null]]]
02-May-2018 18:26:47 18:26:47.038 [main] DEBUG org.springframework.test.context.cache - Spring test ApplicationContext cache statistics: [DefaultContextCache@8c46918 size = 3, maxSize = 32, parentContextCount = 0, hitCount = 33, missCount = 3]
02-May-2018 18:26:47 18:26:47.038 [main] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'org.springframework.boot.test.mock.mockito.MockitoBeans'
02-May-2018 18:26:47 18:26:47.039 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - After test method: context [DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@ebda593, testMethod = testGetAndDelete@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], 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].
02-May-2018 18:26:47 18:26:47.039 [main] DEBUG org.springframework.test.context.web.ServletTestExecutionListener - Resetting RequestContextHolder for test context [DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = org.springframework.analytics.rest.controller.CounterControllerTests@ebda593, testMethod = testGetAndDelete@CounterControllerTests, testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], 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]].
02-May-2018 18:26:47 18:26:47.039 [main] DEBUG io.lettuce.core.RedisChannelHandler - close()
02-May-2018 18:26:47 18:26:47.039 [main] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, epid=0x5] close()
02-May-2018 18:26:47 18:26:47.040 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, chid=0x5] channelInactive()
02-May-2018 18:26:47 18:26:47.040 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, epid=0x5] deactivating endpoint handler
02-May-2018 18:26:47 18:26:47.040 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, chid=0x5] channelInactive() done
02-May-2018 18:26:47 18:26:47.040 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
02-May-2018 18:26:47 18:26:47.040 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
02-May-2018 18:26:47 18:26:47.040 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
02-May-2018 18:26:47 18:26:47.040 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
02-May-2018 18:26:47 18:26:47.040 [lettuce-nioEventLoop-16-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0x05b0e31f, /127.0.0.1:49360 -> localhost/127.0.0.1:6379, chid=0x5] channelUnregistered()
02-May-2018 18:26:48 18:26:48.142 [lettuce-nioEventLoop-16-1] DEBUG io.netty.buffer.PoolThreadCache - Freed 1 thread-local buffer(s) from thread: lettuce-nioEventLoop-16-1
02-May-2018 18:26:48 18:26:48.153 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - After test class: context [DefaultTestContext@1f3b992 testClass = CounterControllerTests, testInstance = [null], testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@2a53f215 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@7ba18f1b, org.springframework.boot.test.json.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer@aecb35a, 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@40f08448, org.springframework.test.context.web.socket.MockServerContainerContextCustomizer@33b37288], 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].
02-May-2018 18:26:48 Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.926 sec - in org.springframework.analytics.rest.controller.CounterControllerTests
02-May-2018 18:26:48 18:26:48.157 [Thread-1] INFO org.springframework.web.context.support.GenericWebApplicationContext - Closing org.springframework.web.context.support.GenericWebApplicationContext@5b239d7d: startup date [Wed May 02 18:26:39 UTC 2018]; root of context hierarchy
02-May-2018 18:26:48 18:26:48.157 [Thread-2] INFO org.springframework.web.context.support.GenericWebApplicationContext - Closing org.springframework.web.context.support.GenericWebApplicationContext@abff8b7: startup date [Wed May 02 18:26:42 UTC 2018]; root of context hierarchy
02-May-2018 18:26:48 18:26:48.158 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'
02-May-2018 18:26:48 18:26:48.158 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'
02-May-2018 18:26:48 18:26:48.161 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@1b4ae4e0: 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
02-May-2018 18:26:48 18:26:48.161 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@15ca7889: 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
02-May-2018 18:26:48 18:26:48.162 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '_linkDiscovererRegistry': [org.springframework.hateoas.LinkDiscoverers#0]
02-May-2018 18:26:48 18:26:48.163 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#131fcb6f': [delegatingEntityLinks]
02-May-2018 18:26:48 18:26:48.164 [Thread-1] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'controllerEntityLinks'
02-May-2018 18:26:48 18:26:48.164 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#6d4a65c6': [controllerEntityLinks]
02-May-2018 18:26:48 18:26:48.164 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#3f36b447': [_relProvider]
02-May-2018 18:26:48 18:26:48.164 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcValidator': [requestMappingHandlerAdapter]
02-May-2018 18:26:48 18:26:48.165 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'requestMappingHandlerAdapter': [mvcUriComponentsContributor]
02-May-2018 18:26:48 18:26:48.165 [Thread-1] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'mvcValidator'
02-May-2018 18:26:48 18:26:48.165 [Thread-1] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcResourceUrlProvider': [requestMappingHandlerMapping]
02-May-2018 18:26:48 18:26:48.161 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '_linkDiscovererRegistry': [org.springframework.hateoas.LinkDiscoverers#0]
02-May-2018 18:26:48 18:26:48.161 [Thread-4] INFO org.springframework.web.context.support.GenericWebApplicationContext - Closing org.springframework.web.context.support.GenericWebApplicationContext@6b63e6ad: startup date [Wed May 02 18:26:45 UTC 2018]; root of context hierarchy
02-May-2018 18:26:48 18:26:48.168 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#168cd36b': [delegatingEntityLinks]
02-May-2018 18:26:48 18:26:48.169 [Thread-2] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'controllerEntityLinks'
02-May-2018 18:26:48 18:26:48.169 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#2903c6ff': [controllerEntityLinks]
02-May-2018 18:26:48 18:26:48.169 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#63411512': [_relProvider]
02-May-2018 18:26:48 18:26:48.169 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcValidator': [requestMappingHandlerAdapter]
02-May-2018 18:26:48 18:26:48.169 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'requestMappingHandlerAdapter': [mvcUriComponentsContributor]
02-May-2018 18:26:48 18:26:48.169 [Thread-2] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'mvcValidator'
02-May-2018 18:26:48 18:26:48.169 [Thread-2] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcResourceUrlProvider': [requestMappingHandlerMapping]
02-May-2018 18:26:48 18:26:48.170 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Returning cached instance of singleton bean 'lifecycleProcessor'
02-May-2018 18:26:48 18:26:48.170 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@59c70ceb: 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
02-May-2018 18:26:48 18:26:48.171 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '_linkDiscovererRegistry': [org.springframework.hateoas.LinkDiscoverers#0]
02-May-2018 18:26:48 18:26:48.171 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#10272bbb': [delegatingEntityLinks]
02-May-2018 18:26:48 18:26:48.172 [Thread-4] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'controllerEntityLinks'
02-May-2018 18:26:48 18:26:48.172 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#999b951': [controllerEntityLinks]
02-May-2018 18:26:48 18:26:48.172 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean '(inner bean)#18b74ea': [_relProvider]
02-May-2018 18:26:48 18:26:48.172 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcValidator': [requestMappingHandlerAdapter]
02-May-2018 18:26:48 18:26:48.173 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'requestMappingHandlerAdapter': [mvcUriComponentsContributor]
02-May-2018 18:26:48 18:26:48.173 [Thread-4] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'mvcValidator'
02-May-2018 18:26:48 18:26:48.173 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'mvcResourceUrlProvider': [requestMappingHandlerMapping]
02-May-2018 18:26:48 18:26:48.173 [Thread-4] DEBUG org.springframework.beans.factory.support.DefaultListableBeanFactory - Retrieved dependent beans for bean 'redisConnectionFactory': [counterControllerTests.Config, redisTemplate, stringRedisTemplate]
02-May-2018 18:26:48 18:26:48.174 [Thread-4] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy() on bean with name 'redisConnectionFactory'
02-May-2018 18:26:48 18:26:48.174 [Thread-4] DEBUG io.lettuce.core.RedisChannelHandler - close()
02-May-2018 18:26:48 18:26:48.174 [Thread-4] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] close()
02-May-2018 18:26:48 18:26:48.174 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] channelInactive()
02-May-2018 18:26:48 18:26:48.175 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.DefaultEndpoint - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, epid=0x4] deactivating endpoint handler
02-May-2018 18:26:48 18:26:48.175 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] channelInactive() done
02-May-2018 18:26:48 18:26:48.175 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
02-May-2018 18:26:48 18:26:48.175 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
02-May-2018 18:26:48 18:26:48.175 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] channelInactive()
02-May-2018 18:26:48 18:26:48.175 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.ConnectionWatchdog - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, last known addr=localhost/127.0.0.1:6379] Reconnect scheduling disabled
02-May-2018 18:26:48 18:26:48.175 [lettuce-nioEventLoop-13-1] DEBUG io.lettuce.core.protocol.CommandHandler - [channel=0xaf74a3aa, /127.0.0.1:49358 -> localhost/127.0.0.1:6379, chid=0x4] channelUnregistered()
02-May-2018 18:26:49 18:26:49.278 [lettuce-nioEventLoop-13-1] DEBUG io.netty.buffer.PoolThreadCache - Freed 20 thread-local buffer(s) from thread: lettuce-nioEventLoop-13-1
02-May-2018 18:26:49 18:26:49.283 [Thread-4] DEBUG org.springframework.beans.factory.support.DisposableBeanAdapter - Invoking destroy method 'shutdown' on bean with name 'lettuceClientResources'
02-May-2018 18:26:49
02-May-2018 18:26:49 Results :
02-May-2018 18:26:49
02-May-2018 18:26:49 Tests run: 6, Failures: 0, Errors: 0, Skipped: 0
02-May-2018 18:26:49
02-May-2018 18:26:49 [INFO]
02-May-2018 18:26:49 [INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ spring-analytics ---
02-May-2018 18:26:50 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/spring-analytics-2.0.0.BUILD-SNAPSHOT.jar
02-May-2018 18:26:50 [INFO]
02-May-2018 18:26:50 [INFO] --- maven-javadoc-plugin:2.10.4:jar (javadoc) @ spring-analytics ---
02-May-2018 18:26:50 [WARNING] Source files encoding has not been set, using platform encoding UTF-8, i.e. build is platform dependent!
02-May-2018 18:26:52 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/spring-analytics-2.0.0.BUILD-SNAPSHOT-javadoc.jar
02-May-2018 18:26:52 [INFO]
02-May-2018 18:26:52 [INFO] >>> maven-source-plugin:3.0.1:jar (attach-sources) > generate-sources @ spring-analytics >>>
02-May-2018 18:26:52 [INFO]
02-May-2018 18:26:52 [INFO] <<< maven-source-plugin:3.0.1:jar (attach-sources) < generate-sources @ spring-analytics <<<
02-May-2018 18:26:52 [INFO]
02-May-2018 18:26:52 [INFO] --- maven-source-plugin:3.0.1:jar (attach-sources) @ spring-analytics ---
02-May-2018 18:26:52 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/spring-analytics-2.0.0.BUILD-SNAPSHOT-sources.jar
02-May-2018 18:26:52 [INFO]
02-May-2018 18:26:52 [INFO] --- maven-install-plugin:2.4:install (default-install) @ spring-analytics ---
02-May-2018 18:26:52 [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
02-May-2018 18:26:52 [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
02-May-2018 18:26:52 [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
02-May-2018 18:26:52 [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
02-May-2018 18:26:53 [INFO] Artifactory Build Info Recorder: Saving Build Info to '/opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1/target/build-info.json'
02-May-2018 18:26:53 [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
02-May-2018 18:26:54 [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
02-May-2018 18:26:54 [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
02-May-2018 18:26:55 [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
02-May-2018 18:26:55 [INFO] Artifactory Build Info Recorder: Deploying build info ...
02-May-2018 18:26:55 [INFO] Deploying build descriptor to: https://repo.spring.io/api/build
02-May-2018 18:26:55 [INFO] Build successfully deployed. Browse it in Artifactory under https://repo.spring.io/webapp/builds/Spring%20Analytics%20-%20Master%20JDK7%20-%20Build%20and%20publish/47
02-May-2018 18:26:55 [INFO] ------------------------------------------------------------------------
02-May-2018 18:26:55 [INFO] BUILD SUCCESS
02-May-2018 18:26:55 [INFO] ------------------------------------------------------------------------
02-May-2018 18:26:55 [INFO] Total time: 01:12 min
02-May-2018 18:26:55 [INFO] Finished at: 2018-05-02T18:26:55+00:00
02-May-2018 18:26:55 [INFO] Final Memory: 52M/463M
02-May-2018 18:26:55 [INFO] ------------------------------------------------------------------------
02-May-2018 18:26:56 Parsing test results under /opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1...
02-May-2018 18:26:56 Finished task 'Build and publish Spring Analytics' with result: Success
02-May-2018 18:26:56 Running post build plugin 'Docker Container Cleanup'
02-May-2018 18:26:56 Running post build plugin 'NCover Results Collector'
02-May-2018 18:26:56 Running post build plugin 'Artifactory Release Management Staging'
02-May-2018 18:26:56 Running post build plugin 'Artifactory Build Info Copier'
02-May-2018 18:26:56 Copying the buildinfo artifacts for build: SA-BMASTER-JOB1-47
02-May-2018 18:26:56 Running post build plugin 'Artifactory Gradle Properties Info Copier'
02-May-2018 18:26:56 Running post build plugin 'Clover Results Collector'
02-May-2018 18:26:56 Running post build plugin 'npm Cache Cleanup'
02-May-2018 18:26:56 Running post build plugin 'Artifact Copier'
02-May-2018 18:26:56 Publishing an artifact: Test Results
02-May-2018 18:26:56 Finished publishing of artifact Shared artifact: [Test Results], pattern: [**/target/surefire-reports/**/*.*] in 26.07 ms
02-May-2018 18:26:56 Successfully removed working directory at '/opt/bamboo-home/xml-data/build-dir/SA-BMASTER-JOB1'
02-May-2018 18:26:56 Finalising the build...
02-May-2018 18:26:56 Stopping timer.
02-May-2018 18:26:56 Build SA-BMASTER-JOB1-47 completed.
02-May-2018 18:26:56 Running on server: post build plugin 'NCover Results Collector'
02-May-2018 18:26:56 Running on server: post build plugin 'Build Hanging Detection Configuration'
02-May-2018 18:26:56 Running on server: post build plugin 'Clover Delta Calculator'
02-May-2018 18:26:56 Running on server: post build plugin 'Maven Dependencies Postprocessor'
02-May-2018 18:26:56 All post build plugins have finished
02-May-2018 18:26:56 Generating build results summary...
02-May-2018 18:26:56 Saving build results to disk...
02-May-2018 18:26:56 Logging substituted variables...
02-May-2018 18:26:56 Indexing build results...
02-May-2018 18:26:56 Finished building SA-BMASTER-JOB1-47.