Build #311

Builds the Spring Cloud Task project

Build: #311 failed

Job: Build Spring Cloud Task Project failed

Stages & jobs

  1. Default Stage

Build log

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

14-Apr-2017 17:22:45 17:22:45.983 [main] DEBUG org.springframework.boot.test.context.SpringBootTestContextBootstrapper - @TestExecutionListeners is not present for class [io.spring.TaskSinkApplicationTests]: using defaults.
14-Apr-2017 17:22:45 17:22:45.995 [main] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Loaded default TestExecutionListener class names from location [META-INF/spring.factories]: [org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener, org.springframework.boot.test.autoconfigure.restdocs.RestDocsTestExecutionListener, org.springframework.boot.test.autoconfigure.web.client.MockRestServiceServerResetTestExecutionListener, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcPrintOnlyOnFailureTestExecutionListener, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverTestExecutionListener, org.springframework.test.context.web.ServletTestExecutionListener, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener, org.springframework.test.context.support.DependencyInjectionTestExecutionListener, org.springframework.test.context.support.DirtiesContextTestExecutionListener, org.springframework.test.context.transaction.TransactionalTestExecutionListener, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener]
14-Apr-2017 17:22:46 17:22:46.000 [main] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@6328d34a, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@145eaa29, org.springframework.boot.test.autoconfigure.SpringBootDependencyInjectionTestExecutionListener@15bb6bea, org.springframework.test.context.support.DirtiesContextTestExecutionListener@8b96fde, org.springframework.test.context.transaction.TransactionalTestExecutionListener@2d2e5f00, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@4c40b76e, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverTestExecutionListener@2ea6137, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener@41ee392b, org.springframework.boot.test.autoconfigure.restdocs.RestDocsTestExecutionListener@1e67a849, org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener@57d5872c, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcPrintOnlyOnFailureTestExecutionListener@667a738, org.springframework.boot.test.autoconfigure.web.client.MockRestServiceServerResetTestExecutionListener@36f0f1be]
14-Apr-2017 17:22:46 17:22:46.000 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [io.spring.TaskSinkApplicationTests]
14-Apr-2017 17:22:46 17:22:46.000 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [io.spring.TaskSinkApplicationTests]
14-Apr-2017 17:22:46 17:22:46.001 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [io.spring.TaskSinkApplicationTests]
14-Apr-2017 17:22:46 17:22:46.001 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [io.spring.TaskSinkApplicationTests]
14-Apr-2017 17:22:46 17:22:46.005 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - Before test class: context [DefaultTestContext@4c6e276e testClass = TaskSinkApplicationTests, testInstance = [null], testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@534df152 testClass = TaskSinkApplicationTests, locations = '{}', classes = '{class io.spring.TaskSinkApplication, class io.spring.TaskSinkApplication}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{org.springframework.boot.test.context.SpringBootTestContextBootstrapper=true}', contextCustomizers = set[org.springframework.boot.test.context.SpringBootTestContextCustomizer@4bbfb90a, org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@5a8806ef, 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@15d0c81b], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.boot.test.context.SpringBootContextLoader', parent = [null]]], class annotated with @DirtiesContext [false] with mode [null].
14-Apr-2017 17:22:46 17:22:46.005 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [io.spring.TaskSinkApplicationTests]
14-Apr-2017 17:22:46 17:22:46.006 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [io.spring.TaskSinkApplicationTests]
14-Apr-2017 17:22:46 17:22:46.069 [main] DEBUG org.springframework.core.env.StandardEnvironment - Adding [systemProperties] PropertySource with lowest search precedence
14-Apr-2017 17:22:46 17:22:46.071 [main] DEBUG org.springframework.core.env.StandardEnvironment - Adding [systemEnvironment] PropertySource with lowest search precedence
14-Apr-2017 17:22:46 17:22:46.072 [main] DEBUG org.springframework.core.env.StandardEnvironment - Initialized StandardEnvironment with PropertySources [systemProperties,systemEnvironment]
14-Apr-2017 17:22:46 17:22:46.073 [main] DEBUG org.springframework.core.env.MutablePropertySources - Adding [inline] PropertySource with highest search precedence
14-Apr-2017 17:22:46 17:22:46.078 [main] DEBUG org.springframework.test.context.support.TestPropertySourceUtils - Adding inlined properties to environment: {spring.jmx.enabled=false, org.springframework.boot.test.context.SpringBootTestContextBootstrapper=true, server.port=-1}
14-Apr-2017 17:22:46 17:22:46.079 [main] DEBUG org.springframework.core.env.StandardEnvironment - Adding [Inlined Test Properties] PropertySource with highest search precedence
14-Apr-2017 17:22:46
14-Apr-2017 17:22:46   .   ____          _            __ _ _
14-Apr-2017 17:22:46 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
14-Apr-2017 17:22:46 ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
14-Apr-2017 17:22:46 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
14-Apr-2017 17:22:46   '  |____| .__|_| |_|_| |_\__, | / / / /
14-Apr-2017 17:22:46 =========|_|==============|___/=/_/_/_/
14-Apr-2017 17:22:46 :: Spring Boot ::        (v1.5.2.RELEASE)
14-Apr-2017 17:22:46
14-Apr-2017 17:22:46 2017-04-14 17:22:46.370  INFO 15870 --- [           main] io.spring.TaskSinkApplicationTests       : Starting TaskSinkApplicationTests on bamboo-12 with PID 15870 (started by bamboo in /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink)
14-Apr-2017 17:22:46 2017-04-14 17:22:46.378  INFO 15870 --- [           main] io.spring.TaskSinkApplicationTests       : No active profile set, falling back to default profiles: default
14-Apr-2017 17:22:46 2017-04-14 17:22:46.421  INFO 15870 --- [           main] o.s.w.c.s.GenericWebApplicationContext   : Refreshing org.springframework.web.context.support.GenericWebApplicationContext@550dbc7a: startup date [Fri Apr 14 17:22:46 UTC 2017]; root of context hierarchy
14-Apr-2017 17:22:47 2017-04-14 17:22:47.225  INFO 15870 --- [           main] o.s.i.config.IntegrationRegistrar        : No bean named 'integrationHeaderChannelRegistry' has been explicitly defined. Therefore, a default DefaultHeaderChannelRegistry will be created.
14-Apr-2017 17:22:47 2017-04-14 17:22:47.261  INFO 15870 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Overriding bean definition for bean 'binderFactory' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.cloud.stream.config.BinderFactoryConfiguration; factoryMethodName=binderFactory; initMethodName=null; destroyMethodName=(inferred); defined in org.springframework.cloud.stream.config.BinderFactoryConfiguration] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.cloud.stream.test.binder.TestSupportBinderAutoConfiguration; factoryMethodName=binderFactory; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/cloud/stream/test/binder/TestSupportBinderAutoConfiguration.class]]
14-Apr-2017 17:22:47 2017-04-14 17:22:47.886  INFO 15870 --- [           main] faultConfiguringBeanFactoryPostProcessor : No bean named 'errorChannel' has been explicitly defined. Therefore, a default PublishSubscribeChannel will be created.
14-Apr-2017 17:22:47 2017-04-14 17:22:47.889  INFO 15870 --- [           main] faultConfiguringBeanFactoryPostProcessor : No bean named 'taskScheduler' has been explicitly defined. Therefore, a default ThreadPoolTaskScheduler will be created.
14-Apr-2017 17:22:48 2017-04-14 17:22:48.070  INFO 15870 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.amqp.rabbit.annotation.RabbitBootstrapConfiguration' of type [org.springframework.amqp.rabbit.annotation.RabbitBootstrapConfiguration$$EnhancerBySpringCGLIB$$3fca7f08] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:48 2017-04-14 17:22:48.128  INFO 15870 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.cloud.stream.config.BindingServiceConfiguration$PostProcessorConfiguration' of type [org.springframework.cloud.stream.config.BindingServiceConfiguration$PostProcessorConfiguration$$EnhancerBySpringCGLIB$$3b265edf] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:48 2017-04-14 17:22:48.139  INFO 15870 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration' of type [org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$795ec0da] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:48 2017-04-14 17:22:48.270  INFO 15870 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'integrationGlobalProperties' of type [org.springframework.beans.factory.config.PropertiesFactoryBean] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:48 2017-04-14 17:22:48.271  INFO 15870 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'integrationGlobalProperties' of type [java.util.Properties] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:49 2017-04-14 17:22:49.081  INFO 15870 --- [           main] o.s.s.c.ThreadPoolTaskScheduler          : Initializing ExecutorService  'taskScheduler'
14-Apr-2017 17:22:49 2017-04-14 17:22:49.690  INFO 15870 --- [           main] s.w.s.m.m.a.RequestMappingHandlerAdapter : Looking for @ControllerAdvice: org.springframework.web.context.support.GenericWebApplicationContext@550dbc7a: startup date [Fri Apr 14 17:22:46 UTC 2017]; root of context hierarchy
14-Apr-2017 17:22:49 2017-04-14 17:22:49.775  INFO 15870 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error],produces=[text/html]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)
14-Apr-2017 17:22:49 2017-04-14 17:22:49.776  INFO 15870 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
14-Apr-2017 17:22:49 2017-04-14 17:22:49.838  INFO 15870 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
14-Apr-2017 17:22:49 2017-04-14 17:22:49.838  INFO 15870 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
14-Apr-2017 17:22:49 2017-04-14 17:22:49.902  INFO 15870 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
14-Apr-2017 17:22:50 2017-04-14 17:22:50.542  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/info || /info.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.543  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/heapdump || /heapdump.json],methods=[GET],produces=[application/octet-stream]}" onto public void org.springframework.boot.actuate.endpoint.mvc.HeapdumpMvcEndpoint.invoke(boolean,javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse) throws java.io.IOException,javax.servlet.ServletException
14-Apr-2017 17:22:50 2017-04-14 17:22:50.544  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/autoconfig || /autoconfig.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.544  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/trace || /trace.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.545  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/metrics/{name:.*}],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.MetricsMvcEndpoint.value(java.lang.String)
14-Apr-2017 17:22:50 2017-04-14 17:22:50.546  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/metrics || /metrics.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.547  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/auditevents || /auditevents.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public org.springframework.http.ResponseEntity<?> org.springframework.boot.actuate.endpoint.mvc.AuditEventsMvcEndpoint.findByPrincipalAndAfterAndType(java.lang.String,java.util.Date,java.lang.String)
14-Apr-2017 17:22:50 2017-04-14 17:22:50.547  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/dump || /dump.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.550  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/loggers/{name:.*}],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.LoggersMvcEndpoint.get(java.lang.String)
14-Apr-2017 17:22:50 2017-04-14 17:22:50.550  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/loggers/{name:.*}],methods=[POST],consumes=[application/vnd.spring-boot.actuator.v1+json || application/json],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.LoggersMvcEndpoint.set(java.lang.String,java.util.Map<java.lang.String, java.lang.String>)
14-Apr-2017 17:22:50 2017-04-14 17:22:50.551  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/loggers || /loggers.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.551  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/mappings || /mappings.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.552  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/channels || /channels.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.553  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/health || /health.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.HealthMvcEndpoint.invoke(javax.servlet.http.HttpServletRequest,java.security.Principal)
14-Apr-2017 17:22:50 2017-04-14 17:22:50.553  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/env/{name:.*}],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EnvironmentMvcEndpoint.value(java.lang.String)
14-Apr-2017 17:22:50 2017-04-14 17:22:50.553  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/env || /env.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.554  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/beans || /beans.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.555  INFO 15870 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/configprops || /configprops.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:50 2017-04-14 17:22:50.759  INFO 15870 --- [           main] o.s.i.codec.kryo.CompositeKryoRegistrar  : configured Kryo registration [40, java.io.File] with serializer org.springframework.integration.codec.kryo.FileSerializer
14-Apr-2017 17:22:51 2017-04-14 17:22:51.118  INFO 15870 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase -2147483648
14-Apr-2017 17:22:51 2017-04-14 17:22:51.119  INFO 15870 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : Adding {service-activator:org.springframework.cloud.task.launcher.TaskLauncherSink.taskLauncherSink.serviceActivator} as a subscriber to the 'input' channel
14-Apr-2017 17:22:51 2017-04-14 17:22:51.119  INFO 15870 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'application:-1.input' has 1 subscriber(s).
14-Apr-2017 17:22:51 2017-04-14 17:22:51.119  INFO 15870 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : started org.springframework.cloud.task.launcher.TaskLauncherSink.taskLauncherSink.serviceActivator
14-Apr-2017 17:22:51 2017-04-14 17:22:51.119  INFO 15870 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase -2147482648
14-Apr-2017 17:22:51 2017-04-14 17:22:51.120  INFO 15870 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 0
14-Apr-2017 17:22:51 2017-04-14 17:22:51.120  INFO 15870 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : Adding {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
14-Apr-2017 17:22:51 2017-04-14 17:22:51.120  INFO 15870 --- [           main] o.s.i.channel.PublishSubscribeChannel    : Channel 'application:-1.errorChannel' has 1 subscriber(s).
14-Apr-2017 17:22:51 2017-04-14 17:22:51.121  INFO 15870 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : started _org.springframework.integration.errorLogger
14-Apr-2017 17:22:51 2017-04-14 17:22:51.121  INFO 15870 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 2147482647
14-Apr-2017 17:22:51 2017-04-14 17:22:51.154  INFO 15870 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 2147483647
14-Apr-2017 17:22:51 2017-04-14 17:22:51.174  INFO 15870 --- [           main] io.spring.TaskSinkApplicationTests       : Started TaskSinkApplicationTests in 5.087 seconds (JVM running for 5.731)
14-Apr-2017 17:22:51 2017-04-14 17:22:51.201  INFO 15870 --- [           main] o.s.c.task.launcher.TaskLauncherSink     : Launching Task for the following resource TaskLaunchRequest{uri='maven://org.springframework.cloud.task.app:timestamp-task:jar:1.0.1.RELEASE', commandlineArguments=[], environmentProperties={server.port=0}, deploymentProperties={}}
14-Apr-2017 17:22:51 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.257 sec - in io.spring.TaskSinkApplicationTests
14-Apr-2017 17:22:51 2017-04-14 17:22:51.255  INFO 15870 --- [       Thread-2] o.s.w.c.s.GenericWebApplicationContext   : Closing org.springframework.web.context.support.GenericWebApplicationContext@550dbc7a: startup date [Fri Apr 14 17:22:46 UTC 2017]; root of context hierarchy
14-Apr-2017 17:22:51 2017-04-14 17:22:51.258  INFO 15870 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase 2147483647
14-Apr-2017 17:22:51 2017-04-14 17:22:51.259  INFO 15870 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase 2147482647
14-Apr-2017 17:22:51 2017-04-14 17:22:51.262  INFO 15870 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase 0
14-Apr-2017 17:22:51 2017-04-14 17:22:51.264  INFO 15870 --- [       Thread-2] o.s.i.endpoint.EventDrivenConsumer       : Removing {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
14-Apr-2017 17:22:51 2017-04-14 17:22:51.265  INFO 15870 --- [       Thread-2] o.s.i.channel.PublishSubscribeChannel    : Channel 'application:-1.errorChannel' has 0 subscriber(s).
14-Apr-2017 17:22:51 2017-04-14 17:22:51.265  INFO 15870 --- [       Thread-2] o.s.i.endpoint.EventDrivenConsumer       : stopped _org.springframework.integration.errorLogger
14-Apr-2017 17:22:51 2017-04-14 17:22:51.265  INFO 15870 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase -2147482648
14-Apr-2017 17:22:51 2017-04-14 17:22:51.265  INFO 15870 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase -2147483648
14-Apr-2017 17:22:51 2017-04-14 17:22:51.265  INFO 15870 --- [       Thread-2] o.s.i.endpoint.EventDrivenConsumer       : Removing {service-activator:org.springframework.cloud.task.launcher.TaskLauncherSink.taskLauncherSink.serviceActivator} as a subscriber to the 'input' channel
14-Apr-2017 17:22:51 2017-04-14 17:22:51.265  INFO 15870 --- [       Thread-2] o.s.integration.channel.DirectChannel    : Channel 'application:-1.input' has 0 subscriber(s).
14-Apr-2017 17:22:51 2017-04-14 17:22:51.265  INFO 15870 --- [       Thread-2] o.s.i.endpoint.EventDrivenConsumer       : stopped org.springframework.cloud.task.launcher.TaskLauncherSink.taskLauncherSink.serviceActivator
14-Apr-2017 17:22:51 2017-04-14 17:22:51.269  INFO 15870 --- [       Thread-2] o.s.s.c.ThreadPoolTaskScheduler          : Shutting down ExecutorService 'taskScheduler'
14-Apr-2017 17:22:51
14-Apr-2017 17:22:51 Results :
14-Apr-2017 17:22:51
14-Apr-2017 17:22:51 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0
14-Apr-2017 17:22:51
14-Apr-2017 17:22:51 [INFO]
14-Apr-2017 17:22:51 [INFO] --- maven-jar-plugin:2.6:jar (default-jar) @ tasksink ---
14-Apr-2017 17:22:51 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/tasksink-1.2.0.BUILD-SNAPSHOT.jar
14-Apr-2017 17:22:51 [INFO]
14-Apr-2017 17:22:51 [INFO] --- spring-boot-maven-plugin:1.5.2.RELEASE:repackage (default) @ tasksink ---
14-Apr-2017 17:22:51 [INFO]
14-Apr-2017 17:22:51 [INFO] --- maven-javadoc-plugin:2.10.3:jar (attach-javadocs) @ tasksink ---
14-Apr-2017 17:22:52 [ERROR] Error fetching link: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-starter-task/target/apidocs/package-list. Ignored it.
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 Loading source files for package io.spring...
14-Apr-2017 17:22:53 Constructing Javadoc information...
14-Apr-2017 17:22:53 Standard Doclet version 1.8.0_121
14-Apr-2017 17:22:53 Building tree for all the packages and classes...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/io/spring/TaskSinkApplication.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/io/spring/package-frame.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/io/spring/package-summary.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/io/spring/package-tree.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/constant-values.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/io/spring/class-use/TaskSinkApplication.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/io/spring/package-use.html...
14-Apr-2017 17:22:53 Building index for all the packages and classes...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/overview-tree.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/index-all.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/deprecated-list.html...
14-Apr-2017 17:22:53 Building index for all classes...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/allclasses-frame.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/allclasses-noframe.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/index.html...
14-Apr-2017 17:22:53 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/apidocs/help-doc.html...
14-Apr-2017 17:22:53 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/tasksink-1.2.0.BUILD-SNAPSHOT-javadoc.jar
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] >>> maven-source-plugin:3.0.1:jar (attach-sources) > generate-sources @ tasksink >>>
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] <<< maven-source-plugin:3.0.1:jar (attach-sources) < generate-sources @ tasksink <<<
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] --- maven-source-plugin:3.0.1:jar (attach-sources) @ tasksink ---
14-Apr-2017 17:22:53 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/tasksink-1.2.0.BUILD-SNAPSHOT-sources.jar
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] --- maven-install-plugin:2.5.2:install (default-install) @ tasksink ---
14-Apr-2017 17:22:53 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/tasksink-1.2.0.BUILD-SNAPSHOT.jar to /home/bamboo/.m2/repository/io/spring/cloud/tasksink/1.2.0.BUILD-SNAPSHOT/tasksink-1.2.0.BUILD-SNAPSHOT.jar
14-Apr-2017 17:22:53 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/pom.xml to /home/bamboo/.m2/repository/io/spring/cloud/tasksink/1.2.0.BUILD-SNAPSHOT/tasksink-1.2.0.BUILD-SNAPSHOT.pom
14-Apr-2017 17:22:53 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/tasksink-1.2.0.BUILD-SNAPSHOT-javadoc.jar to /home/bamboo/.m2/repository/io/spring/cloud/tasksink/1.2.0.BUILD-SNAPSHOT/tasksink-1.2.0.BUILD-SNAPSHOT-javadoc.jar
14-Apr-2017 17:22:53 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/tasksink/target/tasksink-1.2.0.BUILD-SNAPSHOT-sources.jar to /home/bamboo/.m2/repository/io/spring/cloud/tasksink/1.2.0.BUILD-SNAPSHOT/tasksink-1.2.0.BUILD-SNAPSHOT-sources.jar
14-Apr-2017 17:22:53 [INFO]                                                                         
14-Apr-2017 17:22:53 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:22:53 [INFO] Building Task Processor Sample Application 1.2.0.BUILD-SNAPSHOT
14-Apr-2017 17:22:53 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] --- maven-clean-plugin:2.6.1:clean (default-clean) @ taskprocessor ---
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ taskprocessor ---
14-Apr-2017 17:22:53 [INFO] Using 'UTF-8' encoding to copy filtered resources.
14-Apr-2017 17:22:53 [INFO] skip non existing resourceDirectory /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/src/main/resources
14-Apr-2017 17:22:53 [INFO] skip non existing resourceDirectory /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/src/main/resources
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ taskprocessor ---
14-Apr-2017 17:22:53 [INFO] Changes detected - recompiling the module!
14-Apr-2017 17:22:53 [INFO] Compiling 3 source files to /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/classes
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ taskprocessor ---
14-Apr-2017 17:22:53 [INFO] Using 'UTF-8' encoding to copy filtered resources.
14-Apr-2017 17:22:53 [INFO] skip non existing resourceDirectory /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/src/test/resources
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ taskprocessor ---
14-Apr-2017 17:22:53 [INFO] Changes detected - recompiling the module!
14-Apr-2017 17:22:53 [INFO] Compiling 1 source file to /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/test-classes
14-Apr-2017 17:22:53 [WARNING] /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/src/test/java/io/spring/TaskProcessorApplicationTests.java: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/src/test/java/io/spring/TaskProcessorApplicationTests.java uses unchecked or unsafe operations.
14-Apr-2017 17:22:53 [WARNING] /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/src/test/java/io/spring/TaskProcessorApplicationTests.java: Recompile with -Xlint:unchecked for details.
14-Apr-2017 17:22:53 [INFO]
14-Apr-2017 17:22:53 [INFO] --- maven-surefire-plugin:2.18.1:test (default-test) @ taskprocessor ---
14-Apr-2017 17:22:53 [INFO] Surefire report directory: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/surefire-reports
14-Apr-2017 17:22:53
14-Apr-2017 17:22:53 -------------------------------------------------------
14-Apr-2017 17:22:53 T E S T S
14-Apr-2017 17:22:53 -------------------------------------------------------
14-Apr-2017 17:22:54 17:22:54.060 [main] DEBUG org.springframework.test.context.junit4.SpringJUnit4ClassRunner - SpringJUnit4ClassRunner constructor called with [class io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.067 [main] DEBUG org.springframework.test.context.BootstrapUtils - Instantiating CacheAwareContextLoaderDelegate from class [org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate]
14-Apr-2017 17:22:54 17:22:54.075 [main] DEBUG org.springframework.test.context.BootstrapUtils - Instantiating BootstrapContext using constructor [public org.springframework.test.context.support.DefaultBootstrapContext(java.lang.Class,org.springframework.test.context.CacheAwareContextLoaderDelegate)]
14-Apr-2017 17:22:54 17:22:54.092 [main] DEBUG org.springframework.test.context.BootstrapUtils - Instantiating TestContextBootstrapper for test class [io.spring.TaskProcessorApplicationTests] from class [org.springframework.boot.test.context.SpringBootTestContextBootstrapper]
14-Apr-2017 17:22:54 17:22:54.105 [main] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Neither @ContextConfiguration nor @ContextHierarchy found for test class [io.spring.TaskProcessorApplicationTests], using SpringBootContextLoader
14-Apr-2017 17:22:54 17:22:54.110 [main] DEBUG org.springframework.test.context.support.AbstractContextLoader - Did not detect default resource location for test class [io.spring.TaskProcessorApplicationTests]: class path resource [io/spring/TaskProcessorApplicationTests-context.xml] does not exist
14-Apr-2017 17:22:54 17:22:54.110 [main] DEBUG org.springframework.test.context.support.AbstractContextLoader - Did not detect default resource location for test class [io.spring.TaskProcessorApplicationTests]: class path resource [io/spring/TaskProcessorApplicationTestsContext.groovy] does not exist
14-Apr-2017 17:22:54 17:22:54.111 [main] INFO org.springframework.test.context.support.AbstractContextLoader - Could not detect default resource locations for test class [io.spring.TaskProcessorApplicationTests]: no resource found for suffixes {-context.xml, Context.groovy}.
14-Apr-2017 17:22:54 17:22:54.149 [main] DEBUG org.springframework.test.context.support.ActiveProfilesUtils - Could not find an 'annotation declaring class' for annotation type [org.springframework.test.context.ActiveProfiles] and class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.179 [main] DEBUG org.springframework.boot.test.context.SpringBootTestContextBootstrapper - @TestExecutionListeners is not present for class [io.spring.TaskProcessorApplicationTests]: using defaults.
14-Apr-2017 17:22:54 17:22:54.186 [main] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Loaded default TestExecutionListener class names from location [META-INF/spring.factories]: [org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener, org.springframework.boot.test.autoconfigure.restdocs.RestDocsTestExecutionListener, org.springframework.boot.test.autoconfigure.web.client.MockRestServiceServerResetTestExecutionListener, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcPrintOnlyOnFailureTestExecutionListener, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverTestExecutionListener, org.springframework.test.context.web.ServletTestExecutionListener, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener, org.springframework.test.context.support.DependencyInjectionTestExecutionListener, org.springframework.test.context.support.DirtiesContextTestExecutionListener, org.springframework.test.context.transaction.TransactionalTestExecutionListener, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener]
14-Apr-2017 17:22:54 17:22:54.209 [main] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@74294adb, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@70a9f84e, org.springframework.boot.test.autoconfigure.SpringBootDependencyInjectionTestExecutionListener@130f889, org.springframework.test.context.support.DirtiesContextTestExecutionListener@1188e820, org.springframework.test.context.transaction.TransactionalTestExecutionListener@2f490758, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@101df177, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener@166fa74d, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverTestExecutionListener@40f08448, org.springframework.boot.test.autoconfigure.restdocs.RestDocsTestExecutionListener@276438c9, org.springframework.boot.test.autoconfigure.web.client.MockRestServiceServerResetTestExecutionListener@588df31b, org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener@33b37288, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcPrintOnlyOnFailureTestExecutionListener@77a57272]
14-Apr-2017 17:22:54 17:22:54.212 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.213 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 Running io.spring.TaskProcessorApplicationTests
14-Apr-2017 17:22:54 17:22:54.215 [main] DEBUG org.springframework.test.context.junit4.SpringJUnit4ClassRunner - SpringJUnit4ClassRunner constructor called with [class io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.215 [main] DEBUG org.springframework.test.context.BootstrapUtils - Instantiating CacheAwareContextLoaderDelegate from class [org.springframework.test.context.cache.DefaultCacheAwareContextLoaderDelegate]
14-Apr-2017 17:22:54 17:22:54.215 [main] DEBUG org.springframework.test.context.BootstrapUtils - Instantiating BootstrapContext using constructor [public org.springframework.test.context.support.DefaultBootstrapContext(java.lang.Class,org.springframework.test.context.CacheAwareContextLoaderDelegate)]
14-Apr-2017 17:22:54 17:22:54.216 [main] DEBUG org.springframework.test.context.BootstrapUtils - Instantiating TestContextBootstrapper for test class [io.spring.TaskProcessorApplicationTests] from class [org.springframework.boot.test.context.SpringBootTestContextBootstrapper]
14-Apr-2017 17:22:54 17:22:54.217 [main] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Neither @ContextConfiguration nor @ContextHierarchy found for test class [io.spring.TaskProcessorApplicationTests], using SpringBootContextLoader
14-Apr-2017 17:22:54 17:22:54.217 [main] DEBUG org.springframework.test.context.support.AbstractContextLoader - Did not detect default resource location for test class [io.spring.TaskProcessorApplicationTests]: class path resource [io/spring/TaskProcessorApplicationTests-context.xml] does not exist
14-Apr-2017 17:22:54 17:22:54.218 [main] DEBUG org.springframework.test.context.support.AbstractContextLoader - Did not detect default resource location for test class [io.spring.TaskProcessorApplicationTests]: class path resource [io/spring/TaskProcessorApplicationTestsContext.groovy] does not exist
14-Apr-2017 17:22:54 17:22:54.218 [main] INFO org.springframework.test.context.support.AbstractContextLoader - Could not detect default resource locations for test class [io.spring.TaskProcessorApplicationTests]: no resource found for suffixes {-context.xml, Context.groovy}.
14-Apr-2017 17:22:54 17:22:54.233 [main] DEBUG org.springframework.test.context.support.ActiveProfilesUtils - Could not find an 'annotation declaring class' for annotation type [org.springframework.test.context.ActiveProfiles] and class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.237 [main] DEBUG org.springframework.boot.test.context.SpringBootTestContextBootstrapper - @TestExecutionListeners is not present for class [io.spring.TaskProcessorApplicationTests]: using defaults.
14-Apr-2017 17:22:54 17:22:54.241 [main] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Loaded default TestExecutionListener class names from location [META-INF/spring.factories]: [org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener, org.springframework.boot.test.autoconfigure.restdocs.RestDocsTestExecutionListener, org.springframework.boot.test.autoconfigure.web.client.MockRestServiceServerResetTestExecutionListener, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcPrintOnlyOnFailureTestExecutionListener, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverTestExecutionListener, org.springframework.test.context.web.ServletTestExecutionListener, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener, org.springframework.test.context.support.DependencyInjectionTestExecutionListener, org.springframework.test.context.support.DirtiesContextTestExecutionListener, org.springframework.test.context.transaction.TransactionalTestExecutionListener, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener]
14-Apr-2017 17:22:54 17:22:54.246 [main] INFO org.springframework.boot.test.context.SpringBootTestContextBootstrapper - Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@2d2e5f00, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@4c40b76e, org.springframework.boot.test.autoconfigure.SpringBootDependencyInjectionTestExecutionListener@2ea6137, org.springframework.test.context.support.DirtiesContextTestExecutionListener@41ee392b, org.springframework.test.context.transaction.TransactionalTestExecutionListener@1e67a849, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@57d5872c, org.springframework.boot.test.mock.mockito.ResetMocksTestExecutionListener@667a738, org.springframework.boot.test.autoconfigure.web.servlet.WebDriverTestExecutionListener@36f0f1be, org.springframework.boot.test.autoconfigure.restdocs.RestDocsTestExecutionListener@157632c9, org.springframework.boot.test.autoconfigure.web.client.MockRestServiceServerResetTestExecutionListener@6ee12bac, org.springframework.boot.test.mock.mockito.MockitoTestExecutionListener@55040f2f, org.springframework.boot.test.autoconfigure.web.servlet.MockMvcPrintOnlyOnFailureTestExecutionListener@64c87930]
14-Apr-2017 17:22:54 17:22:54.247 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.247 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.248 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.248 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.252 [main] DEBUG org.springframework.test.context.support.AbstractDirtiesContextTestExecutionListener - Before test class: context [DefaultTestContext@8e0379d testClass = TaskProcessorApplicationTests, testInstance = [null], testMethod = [null], testException = [null], mergedContextConfiguration = [WebMergedContextConfiguration@341b80b2 testClass = TaskProcessorApplicationTests, locations = '{}', classes = '{class io.spring.TaskProcessorApplication, class io.spring.TaskProcessorApplication}', contextInitializerClasses = '[]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{org.springframework.boot.test.context.SpringBootTestContextBootstrapper=true}', contextCustomizers = set[org.springframework.boot.test.context.SpringBootTestContextCustomizer@694e1548, org.springframework.boot.test.context.filter.ExcludeFilterContextCustomizer@26aa12dd, 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@1f57539], resourceBasePath = 'src/main/webapp', contextLoader = 'org.springframework.boot.test.context.SpringBootContextLoader', parent = [null]]], class annotated with @DirtiesContext [false] with mode [null].
14-Apr-2017 17:22:54 17:22:54.252 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved @ProfileValueSourceConfiguration [null] for test class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.252 [main] DEBUG org.springframework.test.annotation.ProfileValueUtils - Retrieved ProfileValueSource type [class org.springframework.test.annotation.SystemProfileValueSource] for class [io.spring.TaskProcessorApplicationTests]
14-Apr-2017 17:22:54 17:22:54.324 [main] DEBUG org.springframework.core.env.StandardEnvironment - Adding [systemProperties] PropertySource with lowest search precedence
14-Apr-2017 17:22:54 17:22:54.335 [main] DEBUG org.springframework.core.env.StandardEnvironment - Adding [systemEnvironment] PropertySource with lowest search precedence
14-Apr-2017 17:22:54 17:22:54.336 [main] DEBUG org.springframework.core.env.StandardEnvironment - Initialized StandardEnvironment with PropertySources [systemProperties,systemEnvironment]
14-Apr-2017 17:22:54 17:22:54.338 [main] DEBUG org.springframework.core.env.MutablePropertySources - Adding [inline] PropertySource with highest search precedence
14-Apr-2017 17:22:54 17:22:54.343 [main] DEBUG org.springframework.test.context.support.TestPropertySourceUtils - Adding inlined properties to environment: {spring.jmx.enabled=false, org.springframework.boot.test.context.SpringBootTestContextBootstrapper=true, server.port=-1}
14-Apr-2017 17:22:54 17:22:54.343 [main] DEBUG org.springframework.core.env.StandardEnvironment - Adding [Inlined Test Properties] PropertySource with highest search precedence
14-Apr-2017 17:22:54
14-Apr-2017 17:22:54   .   ____          _            __ _ _
14-Apr-2017 17:22:54 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
14-Apr-2017 17:22:54 ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
14-Apr-2017 17:22:54 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
14-Apr-2017 17:22:54   '  |____| .__|_| |_|_| |_\__, | / / / /
14-Apr-2017 17:22:54 =========|_|==============|___/=/_/_/_/
14-Apr-2017 17:22:54 :: Spring Boot ::        (v1.5.2.RELEASE)
14-Apr-2017 17:22:54
14-Apr-2017 17:22:54 2017-04-14 17:22:54.651  INFO 15938 --- [           main] io.spring.TaskProcessorApplicationTests  : Starting TaskProcessorApplicationTests on bamboo-12 with PID 15938 (started by bamboo in /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor)
14-Apr-2017 17:22:54 2017-04-14 17:22:54.652  INFO 15938 --- [           main] io.spring.TaskProcessorApplicationTests  : No active profile set, falling back to default profiles: default
14-Apr-2017 17:22:54 2017-04-14 17:22:54.689  INFO 15938 --- [           main] o.s.w.c.s.GenericWebApplicationContext   : Refreshing org.springframework.web.context.support.GenericWebApplicationContext@f0da945: startup date [Fri Apr 14 17:22:54 UTC 2017]; root of context hierarchy
14-Apr-2017 17:22:55 2017-04-14 17:22:55.529  INFO 15938 --- [           main] o.s.i.config.IntegrationRegistrar        : No bean named 'integrationHeaderChannelRegistry' has been explicitly defined. Therefore, a default DefaultHeaderChannelRegistry will be created.
14-Apr-2017 17:22:55 2017-04-14 17:22:55.581  INFO 15938 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Overriding bean definition for bean 'binderFactory' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.cloud.stream.config.BinderFactoryConfiguration; factoryMethodName=binderFactory; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/cloud/stream/config/BinderFactoryConfiguration.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.cloud.stream.test.binder.TestSupportBinderAutoConfiguration; factoryMethodName=binderFactory; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/cloud/stream/test/binder/TestSupportBinderAutoConfiguration.class]]
14-Apr-2017 17:22:56 2017-04-14 17:22:56.148  INFO 15938 --- [           main] faultConfiguringBeanFactoryPostProcessor : No bean named 'errorChannel' has been explicitly defined. Therefore, a default PublishSubscribeChannel will be created.
14-Apr-2017 17:22:56 2017-04-14 17:22:56.150  INFO 15938 --- [           main] faultConfiguringBeanFactoryPostProcessor : No bean named 'taskScheduler' has been explicitly defined. Therefore, a default ThreadPoolTaskScheduler will be created.
14-Apr-2017 17:22:56 2017-04-14 17:22:56.335  INFO 15938 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.amqp.rabbit.annotation.RabbitBootstrapConfiguration' of type [org.springframework.amqp.rabbit.annotation.RabbitBootstrapConfiguration$$EnhancerBySpringCGLIB$$6125f5b3] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:56 2017-04-14 17:22:56.396  INFO 15938 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.cloud.stream.config.BindingServiceConfiguration$PostProcessorConfiguration' of type [org.springframework.cloud.stream.config.BindingServiceConfiguration$PostProcessorConfiguration$$EnhancerBySpringCGLIB$$5c81d58a] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:56 2017-04-14 17:22:56.407  INFO 15938 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration' of type [org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$9aba3785] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:56 2017-04-14 17:22:56.559  INFO 15938 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'integrationGlobalProperties' of type [org.springframework.beans.factory.config.PropertiesFactoryBean] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:56 2017-04-14 17:22:56.560  INFO 15938 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'integrationGlobalProperties' of type [java.util.Properties] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:22:57 2017-04-14 17:22:57.245  INFO 15938 --- [           main] o.s.s.c.ThreadPoolTaskScheduler          : Initializing ExecutorService  'taskScheduler'
14-Apr-2017 17:22:57 2017-04-14 17:22:57.897  INFO 15938 --- [           main] s.w.s.m.m.a.RequestMappingHandlerAdapter : Looking for @ControllerAdvice: org.springframework.web.context.support.GenericWebApplicationContext@f0da945: startup date [Fri Apr 14 17:22:54 UTC 2017]; root of context hierarchy
14-Apr-2017 17:22:57 2017-04-14 17:22:57.986  INFO 15938 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error],produces=[text/html]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)
14-Apr-2017 17:22:57 2017-04-14 17:22:57.987  INFO 15938 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
14-Apr-2017 17:22:58 2017-04-14 17:22:58.056  INFO 15938 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
14-Apr-2017 17:22:58 2017-04-14 17:22:58.056  INFO 15938 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
14-Apr-2017 17:22:58 2017-04-14 17:22:58.132  INFO 15938 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
14-Apr-2017 17:22:58 2017-04-14 17:22:58.844  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/loggers/{name:.*}],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.LoggersMvcEndpoint.get(java.lang.String)
14-Apr-2017 17:22:58 2017-04-14 17:22:58.845  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/loggers/{name:.*}],methods=[POST],consumes=[application/vnd.spring-boot.actuator.v1+json || application/json],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.LoggersMvcEndpoint.set(java.lang.String,java.util.Map<java.lang.String, java.lang.String>)
14-Apr-2017 17:22:58 2017-04-14 17:22:58.845  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/loggers || /loggers.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.846  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/dump || /dump.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.847  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/heapdump || /heapdump.json],methods=[GET],produces=[application/octet-stream]}" onto public void org.springframework.boot.actuate.endpoint.mvc.HeapdumpMvcEndpoint.invoke(boolean,javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse) throws java.io.IOException,javax.servlet.ServletException
14-Apr-2017 17:22:58 2017-04-14 17:22:58.848  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/env/{name:.*}],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EnvironmentMvcEndpoint.value(java.lang.String)
14-Apr-2017 17:22:58 2017-04-14 17:22:58.848  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/env || /env.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.850  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/health || /health.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.HealthMvcEndpoint.invoke(javax.servlet.http.HttpServletRequest,java.security.Principal)
14-Apr-2017 17:22:58 2017-04-14 17:22:58.850  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/configprops || /configprops.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.853  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/beans || /beans.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.854  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/channels || /channels.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.855  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/autoconfig || /autoconfig.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.856  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/auditevents || /auditevents.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public org.springframework.http.ResponseEntity<?> org.springframework.boot.actuate.endpoint.mvc.AuditEventsMvcEndpoint.findByPrincipalAndAfterAndType(java.lang.String,java.util.Date,java.lang.String)
14-Apr-2017 17:22:58 2017-04-14 17:22:58.857  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/trace || /trace.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.857  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/info || /info.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.858  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/mappings || /mappings.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:58 2017-04-14 17:22:58.859  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/metrics/{name:.*}],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.MetricsMvcEndpoint.value(java.lang.String)
14-Apr-2017 17:22:58 2017-04-14 17:22:58.859  INFO 15938 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/metrics || /metrics.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:22:59 2017-04-14 17:22:59.077  INFO 15938 --- [           main] o.s.i.codec.kryo.CompositeKryoRegistrar  : configured Kryo registration [40, java.io.File] with serializer org.springframework.integration.codec.kryo.FileSerializer
14-Apr-2017 17:22:59 2017-04-14 17:22:59.273  INFO 15938 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase -2147483648
14-Apr-2017 17:22:59 2017-04-14 17:22:59.274  INFO 15938 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : Adding {transformer:taskProcessor.setupRequest.transformer} as a subscriber to the 'input' channel
14-Apr-2017 17:22:59 2017-04-14 17:22:59.274  INFO 15938 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'application:-1.input' has 1 subscriber(s).
14-Apr-2017 17:22:59 2017-04-14 17:22:59.274  INFO 15938 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : started taskProcessor.setupRequest.transformer
14-Apr-2017 17:22:59 2017-04-14 17:22:59.274  INFO 15938 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase -2147482648
14-Apr-2017 17:22:59 2017-04-14 17:22:59.314  INFO 15938 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'application:-1.output' has 1 subscriber(s).
14-Apr-2017 17:22:59 2017-04-14 17:22:59.315  INFO 15938 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 0
14-Apr-2017 17:22:59 2017-04-14 17:22:59.315  INFO 15938 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : Adding {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
14-Apr-2017 17:22:59 2017-04-14 17:22:59.316  INFO 15938 --- [           main] o.s.i.channel.PublishSubscribeChannel    : Channel 'application:-1.errorChannel' has 1 subscriber(s).
14-Apr-2017 17:22:59 2017-04-14 17:22:59.316  INFO 15938 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : started _org.springframework.integration.errorLogger
14-Apr-2017 17:22:59 2017-04-14 17:22:59.316  INFO 15938 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 2147482647
14-Apr-2017 17:22:59 2017-04-14 17:22:59.323  INFO 15938 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 2147483647
14-Apr-2017 17:22:59 2017-04-14 17:22:59.344  INFO 15938 --- [           main] io.spring.TaskProcessorApplicationTests  : Started TaskProcessorApplicationTests in 4.992 seconds (JVM running for 5.673)
14-Apr-2017 17:22:59 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.189 sec - in io.spring.TaskProcessorApplicationTests
14-Apr-2017 17:22:59 2017-04-14 17:22:59.409  INFO 15938 --- [       Thread-2] o.s.w.c.s.GenericWebApplicationContext   : Closing org.springframework.web.context.support.GenericWebApplicationContext@f0da945: startup date [Fri Apr 14 17:22:54 UTC 2017]; root of context hierarchy
14-Apr-2017 17:22:59 2017-04-14 17:22:59.412  INFO 15938 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase 2147483647
14-Apr-2017 17:22:59 2017-04-14 17:22:59.412  INFO 15938 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase 2147482647
14-Apr-2017 17:22:59 2017-04-14 17:22:59.413  INFO 15938 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase 0
14-Apr-2017 17:22:59 2017-04-14 17:22:59.415  INFO 15938 --- [       Thread-2] o.s.i.endpoint.EventDrivenConsumer       : Removing {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
14-Apr-2017 17:22:59 2017-04-14 17:22:59.416  INFO 15938 --- [       Thread-2] o.s.i.channel.PublishSubscribeChannel    : Channel 'application:-1.errorChannel' has 0 subscriber(s).
14-Apr-2017 17:22:59 2017-04-14 17:22:59.416  INFO 15938 --- [       Thread-2] o.s.i.endpoint.EventDrivenConsumer       : stopped _org.springframework.integration.errorLogger
14-Apr-2017 17:22:59 2017-04-14 17:22:59.416  INFO 15938 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase -2147482648
14-Apr-2017 17:22:59 2017-04-14 17:22:59.417  INFO 15938 --- [       Thread-2] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase -2147483648
14-Apr-2017 17:22:59 2017-04-14 17:22:59.417  INFO 15938 --- [       Thread-2] o.s.i.endpoint.EventDrivenConsumer       : Removing {transformer:taskProcessor.setupRequest.transformer} as a subscriber to the 'input' channel
14-Apr-2017 17:22:59 2017-04-14 17:22:59.417  INFO 15938 --- [       Thread-2] o.s.integration.channel.DirectChannel    : Channel 'application:-1.input' has 0 subscriber(s).
14-Apr-2017 17:22:59 2017-04-14 17:22:59.418  INFO 15938 --- [       Thread-2] o.s.i.endpoint.EventDrivenConsumer       : stopped taskProcessor.setupRequest.transformer
14-Apr-2017 17:22:59 2017-04-14 17:22:59.423  INFO 15938 --- [       Thread-2] o.s.s.c.ThreadPoolTaskScheduler          : Shutting down ExecutorService 'taskScheduler'
14-Apr-2017 17:22:59
14-Apr-2017 17:22:59 Results :
14-Apr-2017 17:22:59
14-Apr-2017 17:22:59 Tests run: 1, Failures: 0, Errors: 0, Skipped: 0
14-Apr-2017 17:22:59
14-Apr-2017 17:22:59 [INFO]
14-Apr-2017 17:22:59 [INFO] --- maven-jar-plugin:2.6:jar (default-jar) @ taskprocessor ---
14-Apr-2017 17:22:59 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/taskprocessor-1.2.0.BUILD-SNAPSHOT.jar
14-Apr-2017 17:22:59 [INFO]
14-Apr-2017 17:22:59 [INFO] --- spring-boot-maven-plugin:1.5.2.RELEASE:repackage (default) @ taskprocessor ---
14-Apr-2017 17:22:59 [INFO]
14-Apr-2017 17:22:59 [INFO] --- maven-javadoc-plugin:2.10.3:jar (attach-javadocs) @ taskprocessor ---
14-Apr-2017 17:22:59 [ERROR] Error fetching link: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-starter-task/target/apidocs/package-list. Ignored it.
14-Apr-2017 17:23:00 [INFO]
14-Apr-2017 17:23:00 Loading source files for package io.spring...
14-Apr-2017 17:23:00 Constructing Javadoc information...
14-Apr-2017 17:23:00 Standard Doclet version 1.8.0_121
14-Apr-2017 17:23:00 Building tree for all the packages and classes...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/TaskProcessor.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/TaskProcessorApplication.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/TaskProcessorProperties.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/package-frame.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/package-summary.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/package-tree.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/constant-values.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/class-use/TaskProcessorApplication.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/class-use/TaskProcessor.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/class-use/TaskProcessorProperties.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/io/spring/package-use.html...
14-Apr-2017 17:23:00 Building index for all the packages and classes...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/overview-tree.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/index-all.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/deprecated-list.html...
14-Apr-2017 17:23:00 Building index for all classes...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/allclasses-frame.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/allclasses-noframe.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/index.html...
14-Apr-2017 17:23:00 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/apidocs/help-doc.html...
14-Apr-2017 17:23:00 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/taskprocessor-1.2.0.BUILD-SNAPSHOT-javadoc.jar
14-Apr-2017 17:23:00 [INFO]
14-Apr-2017 17:23:00 [INFO] >>> maven-source-plugin:3.0.1:jar (attach-sources) > generate-sources @ taskprocessor >>>
14-Apr-2017 17:23:00 [INFO]
14-Apr-2017 17:23:00 [INFO] <<< maven-source-plugin:3.0.1:jar (attach-sources) < generate-sources @ taskprocessor <<<
14-Apr-2017 17:23:00 [INFO]
14-Apr-2017 17:23:00 [INFO] --- maven-source-plugin:3.0.1:jar (attach-sources) @ taskprocessor ---
14-Apr-2017 17:23:00 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/taskprocessor-1.2.0.BUILD-SNAPSHOT-sources.jar
14-Apr-2017 17:23:00 [INFO]
14-Apr-2017 17:23:00 [INFO] --- maven-install-plugin:2.5.2:install (default-install) @ taskprocessor ---
14-Apr-2017 17:23:00 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/taskprocessor-1.2.0.BUILD-SNAPSHOT.jar to /home/bamboo/.m2/repository/io/spring/cloud/taskprocessor/1.2.0.BUILD-SNAPSHOT/taskprocessor-1.2.0.BUILD-SNAPSHOT.jar
14-Apr-2017 17:23:01 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/pom.xml to /home/bamboo/.m2/repository/io/spring/cloud/taskprocessor/1.2.0.BUILD-SNAPSHOT/taskprocessor-1.2.0.BUILD-SNAPSHOT.pom
14-Apr-2017 17:23:01 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/taskprocessor-1.2.0.BUILD-SNAPSHOT-javadoc.jar to /home/bamboo/.m2/repository/io/spring/cloud/taskprocessor/1.2.0.BUILD-SNAPSHOT/taskprocessor-1.2.0.BUILD-SNAPSHOT-javadoc.jar
14-Apr-2017 17:23:01 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/taskprocessor/target/taskprocessor-1.2.0.BUILD-SNAPSHOT-sources.jar to /home/bamboo/.m2/repository/io/spring/cloud/taskprocessor/1.2.0.BUILD-SNAPSHOT/taskprocessor-1.2.0.BUILD-SNAPSHOT-sources.jar
14-Apr-2017 17:23:01 [INFO]                                                                         
14-Apr-2017 17:23:01 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:23:01 [INFO] Building Partitioned Batch Job 1.2.0.BUILD-SNAPSHOT
14-Apr-2017 17:23:01 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:23:01 [INFO]
14-Apr-2017 17:23:01 [INFO] --- maven-clean-plugin:2.6.1:clean (default-clean) @ partitioned-batch-job ---
14-Apr-2017 17:23:01 [INFO]
14-Apr-2017 17:23:01 [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ partitioned-batch-job ---
14-Apr-2017 17:23:01 [INFO] Using 'UTF-8' encoding to copy filtered resources.
14-Apr-2017 17:23:01 [INFO] Copying 1 resource
14-Apr-2017 17:23:01 [INFO] Copying 0 resource
14-Apr-2017 17:23:01 [INFO]
14-Apr-2017 17:23:01 [INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ partitioned-batch-job ---
14-Apr-2017 17:23:01 [INFO] Changes detected - recompiling the module!
14-Apr-2017 17:23:01 [INFO] Compiling 2 source files to /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/classes
14-Apr-2017 17:23:01 [INFO]
14-Apr-2017 17:23:01 [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ partitioned-batch-job ---
14-Apr-2017 17:23:01 [INFO] Using 'UTF-8' encoding to copy filtered resources.
14-Apr-2017 17:23:01 [INFO] skip non existing resourceDirectory /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/src/test/resources
14-Apr-2017 17:23:01 [INFO]
14-Apr-2017 17:23:01 [INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ partitioned-batch-job ---
14-Apr-2017 17:23:01 [INFO] Changes detected - recompiling the module!
14-Apr-2017 17:23:01 [INFO] Compiling 1 source file to /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/test-classes
14-Apr-2017 17:23:01 [INFO]
14-Apr-2017 17:23:01 [INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ partitioned-batch-job ---
14-Apr-2017 17:23:01 [INFO]
14-Apr-2017 17:23:01 [INFO] --- maven-jar-plugin:2.6:jar (default-jar) @ partitioned-batch-job ---
14-Apr-2017 17:23:01 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT.jar
14-Apr-2017 17:23:01 [INFO]
14-Apr-2017 17:23:01 [INFO] --- spring-boot-maven-plugin:1.5.2.RELEASE:repackage (default) @ partitioned-batch-job ---
14-Apr-2017 17:23:01 [INFO]
14-Apr-2017 17:23:01 [INFO] --- maven-javadoc-plugin:2.10.3:jar (attach-javadocs) @ partitioned-batch-job ---
14-Apr-2017 17:23:01 [ERROR] Error fetching link: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-starter-task/target/apidocs/package-list. Ignored it.
14-Apr-2017 17:23:02 [INFO]
14-Apr-2017 17:23:02 Loading source files for package io.spring...
14-Apr-2017 17:23:02 Constructing Javadoc information...
14-Apr-2017 17:23:02 Standard Doclet version 1.8.0_121
14-Apr-2017 17:23:02 Building tree for all the packages and classes...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/io/spring/JobConfiguration.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/io/spring/PartitionedBatchJobApplication.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/io/spring/package-frame.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/io/spring/package-summary.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/io/spring/package-tree.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/constant-values.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/io/spring/class-use/JobConfiguration.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/io/spring/class-use/PartitionedBatchJobApplication.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/io/spring/package-use.html...
14-Apr-2017 17:23:02 Building index for all the packages and classes...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/overview-tree.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/index-all.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/deprecated-list.html...
14-Apr-2017 17:23:02 Building index for all classes...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/allclasses-frame.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/allclasses-noframe.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/index.html...
14-Apr-2017 17:23:02 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/apidocs/help-doc.html...
14-Apr-2017 17:23:02 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT-javadoc.jar
14-Apr-2017 17:23:02 [INFO]
14-Apr-2017 17:23:02 [INFO] >>> maven-source-plugin:3.0.1:jar (attach-sources) > generate-sources @ partitioned-batch-job >>>
14-Apr-2017 17:23:02 [INFO]
14-Apr-2017 17:23:02 [INFO] <<< maven-source-plugin:3.0.1:jar (attach-sources) < generate-sources @ partitioned-batch-job <<<
14-Apr-2017 17:23:02 [INFO]
14-Apr-2017 17:23:02 [INFO] --- maven-source-plugin:3.0.1:jar (attach-sources) @ partitioned-batch-job ---
14-Apr-2017 17:23:02 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT-sources.jar
14-Apr-2017 17:23:02 [INFO]
14-Apr-2017 17:23:02 [INFO] --- maven-install-plugin:2.5.2:install (default-install) @ partitioned-batch-job ---
14-Apr-2017 17:23:02 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT.jar to /home/bamboo/.m2/repository/io/spring/cloud/partitioned-batch-job/1.2.0.BUILD-SNAPSHOT/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT.jar
14-Apr-2017 17:23:02 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/pom.xml to /home/bamboo/.m2/repository/io/spring/cloud/partitioned-batch-job/1.2.0.BUILD-SNAPSHOT/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT.pom
14-Apr-2017 17:23:02 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT-javadoc.jar to /home/bamboo/.m2/repository/io/spring/cloud/partitioned-batch-job/1.2.0.BUILD-SNAPSHOT/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT-javadoc.jar
14-Apr-2017 17:23:02 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/partitioned-batch-job/target/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT-sources.jar to /home/bamboo/.m2/repository/io/spring/cloud/partitioned-batch-job/1.2.0.BUILD-SNAPSHOT/partitioned-batch-job-1.2.0.BUILD-SNAPSHOT-sources.jar
14-Apr-2017 17:23:03 [INFO]                                                                         
14-Apr-2017 17:23:03 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:23:03 [INFO] Building Task Events 1.2.0.BUILD-SNAPSHOT
14-Apr-2017 17:23:03 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:23:03 [INFO]
14-Apr-2017 17:23:03 [INFO] --- maven-clean-plugin:2.6.1:clean (default-clean) @ task-events ---
14-Apr-2017 17:23:03 [INFO]
14-Apr-2017 17:23:03 [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ task-events ---
14-Apr-2017 17:23:03 [INFO] Using 'UTF-8' encoding to copy filtered resources.
14-Apr-2017 17:23:03 [INFO] Copying 1 resource
14-Apr-2017 17:23:03 [INFO] Copying 0 resource
14-Apr-2017 17:23:03 [INFO]
14-Apr-2017 17:23:03 [INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ task-events ---
14-Apr-2017 17:23:03 [INFO] Changes detected - recompiling the module!
14-Apr-2017 17:23:03 [INFO] Compiling 1 source file to /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/classes
14-Apr-2017 17:23:03 [INFO]
14-Apr-2017 17:23:03 [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ task-events ---
14-Apr-2017 17:23:03 [INFO] Using 'UTF-8' encoding to copy filtered resources.
14-Apr-2017 17:23:03 [INFO] skip non existing resourceDirectory /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/src/test/resources
14-Apr-2017 17:23:03 [INFO]
14-Apr-2017 17:23:03 [INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ task-events ---
14-Apr-2017 17:23:03 [INFO] No sources to compile
14-Apr-2017 17:23:03 [INFO]
14-Apr-2017 17:23:03 [INFO] --- maven-surefire-plugin:2.18.1:test (default-test) @ task-events ---
14-Apr-2017 17:23:03 [INFO] No tests to run.
14-Apr-2017 17:23:03 [INFO]
14-Apr-2017 17:23:03 [INFO] --- maven-jar-plugin:2.6:jar (default-jar) @ task-events ---
14-Apr-2017 17:23:03 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/task-events-1.2.0.BUILD-SNAPSHOT.jar
14-Apr-2017 17:23:03 [INFO]
14-Apr-2017 17:23:03 [INFO] --- spring-boot-maven-plugin:1.5.2.RELEASE:repackage (default) @ task-events ---
14-Apr-2017 17:23:03 [INFO]
14-Apr-2017 17:23:03 [INFO] --- maven-javadoc-plugin:2.10.3:jar (attach-javadocs) @ task-events ---
14-Apr-2017 17:23:03 [ERROR] Error fetching link: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-starter-task/target/apidocs/package-list. Ignored it.
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 Loading source files for package io.spring...
14-Apr-2017 17:23:04 Constructing Javadoc information...
14-Apr-2017 17:23:04 Standard Doclet version 1.8.0_121
14-Apr-2017 17:23:04 Building tree for all the packages and classes...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/io/spring/TaskEventsApplication.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/io/spring/TaskEventsApplication.TaskConfiguration.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/io/spring/package-frame.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/io/spring/package-summary.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/io/spring/package-tree.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/constant-values.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/io/spring/class-use/TaskEventsApplication.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/io/spring/class-use/TaskEventsApplication.TaskConfiguration.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/io/spring/package-use.html...
14-Apr-2017 17:23:04 Building index for all the packages and classes...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/overview-tree.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/index-all.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/deprecated-list.html...
14-Apr-2017 17:23:04 Building index for all classes...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/allclasses-frame.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/allclasses-noframe.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/index.html...
14-Apr-2017 17:23:04 Generating /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/apidocs/help-doc.html...
14-Apr-2017 17:23:04 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/task-events-1.2.0.BUILD-SNAPSHOT-javadoc.jar
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] >>> maven-source-plugin:3.0.1:jar (attach-sources) > generate-sources @ task-events >>>
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] <<< maven-source-plugin:3.0.1:jar (attach-sources) < generate-sources @ task-events <<<
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] --- maven-source-plugin:3.0.1:jar (attach-sources) @ task-events ---
14-Apr-2017 17:23:04 [INFO] Building jar: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/task-events-1.2.0.BUILD-SNAPSHOT-sources.jar
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] --- maven-install-plugin:2.5.2:install (default-install) @ task-events ---
14-Apr-2017 17:23:04 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/task-events-1.2.0.BUILD-SNAPSHOT.jar to /home/bamboo/.m2/repository/io/spring/cloud/task-events/1.2.0.BUILD-SNAPSHOT/task-events-1.2.0.BUILD-SNAPSHOT.jar
14-Apr-2017 17:23:04 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/pom.xml to /home/bamboo/.m2/repository/io/spring/cloud/task-events/1.2.0.BUILD-SNAPSHOT/task-events-1.2.0.BUILD-SNAPSHOT.pom
14-Apr-2017 17:23:04 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/task-events-1.2.0.BUILD-SNAPSHOT-javadoc.jar to /home/bamboo/.m2/repository/io/spring/cloud/task-events/1.2.0.BUILD-SNAPSHOT/task-events-1.2.0.BUILD-SNAPSHOT-javadoc.jar
14-Apr-2017 17:23:04 [INFO] Installing /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/task-events/target/task-events-1.2.0.BUILD-SNAPSHOT-sources.jar to /home/bamboo/.m2/repository/io/spring/cloud/task-events/1.2.0.BUILD-SNAPSHOT/task-events-1.2.0.BUILD-SNAPSHOT-sources.jar
14-Apr-2017 17:23:04 [INFO]                                                                         
14-Apr-2017 17:23:04 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:23:04 [INFO] Building Batch Events Sample Application 1.2.0.BUILD-SNAPSHOT
14-Apr-2017 17:23:04 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] --- maven-clean-plugin:2.6.1:clean (default-clean) @ batch-events ---
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ batch-events ---
14-Apr-2017 17:23:04 [INFO] Using 'UTF-8' encoding to copy filtered resources.
14-Apr-2017 17:23:04 [INFO] Copying 1 resource
14-Apr-2017 17:23:04 [INFO] Copying 0 resource
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ batch-events ---
14-Apr-2017 17:23:04 [INFO] Changes detected - recompiling the module!
14-Apr-2017 17:23:04 [INFO] Compiling 1 source file to /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/batch-events/target/classes
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ batch-events ---
14-Apr-2017 17:23:04 [INFO] Using 'UTF-8' encoding to copy filtered resources.
14-Apr-2017 17:23:04 [INFO] Copying 1 resource
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ batch-events ---
14-Apr-2017 17:23:04 [INFO] Changes detected - recompiling the module!
14-Apr-2017 17:23:04 [INFO] Compiling 1 source file to /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/batch-events/target/test-classes
14-Apr-2017 17:23:04 [INFO]
14-Apr-2017 17:23:04 [INFO] --- maven-surefire-plugin:2.18.1:test (default-test) @ batch-events ---
14-Apr-2017 17:23:04 [INFO] Surefire report directory: /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/batch-events/target/surefire-reports
14-Apr-2017 17:23:04
14-Apr-2017 17:23:04 -------------------------------------------------------
14-Apr-2017 17:23:04 T E S T S
14-Apr-2017 17:23:04 -------------------------------------------------------
14-Apr-2017 17:23:05 Running io.spring.cloud.BatchEventsApplicationTests
14-Apr-2017 17:23:05 17:23:05.475 [main] INFO org.springframework.amqp.rabbit.connection.CachingConnectionFactory - Created new connection: SimpleConnection@490ab905 [delegate=amqp://guest@127.0.0.1:5672/, localPort= 34716]
14-Apr-2017 17:23:05
14-Apr-2017 17:23:05   .   ____          _            __ _ _
14-Apr-2017 17:23:05 /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
14-Apr-2017 17:23:05 ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
14-Apr-2017 17:23:05 \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
14-Apr-2017 17:23:05   '  |____| .__|_| |_|_| |_\__, | / / / /
14-Apr-2017 17:23:05 =========|_|==============|___/=/_/_/_/
14-Apr-2017 17:23:05 :: Spring Boot ::        (v1.5.2.RELEASE)
14-Apr-2017 17:23:05
14-Apr-2017 17:23:05 2017-04-14 17:23:05.981  INFO 16083 --- [           main] o.a.maven.surefire.booter.ForkedBooter   : Starting ForkedBooter v2.18.1 on bamboo-12 with PID 16083 (started by bamboo in /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/batch-events)
14-Apr-2017 17:23:05 2017-04-14 17:23:05.982  INFO 16083 --- [           main] o.a.maven.surefire.booter.ForkedBooter   : No active profile set, falling back to default profiles: default
14-Apr-2017 17:23:06 2017-04-14 17:23:06.041  INFO 16083 --- [           main] ationConfigEmbeddedWebApplicationContext : Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@7a8c8dcf: startup date [Fri Apr 14 17:23:06 UTC 2017]; root of context hierarchy
14-Apr-2017 17:23:06 2017-04-14 17:23:06.895  INFO 16083 --- [           main] o.s.i.config.IntegrationRegistrar        : No bean named 'integrationHeaderChannelRegistry' has been explicitly defined. Therefore, a default DefaultHeaderChannelRegistry will be created.
14-Apr-2017 17:23:06 2017-04-14 17:23:06.898 DEBUG 16083 --- [           main] o.s.i.config.IntegrationRegistrar        : SpEL function '#xpath' isn't registered: there is no spring-integration-xml.jar on the classpath.
14-Apr-2017 17:23:06 2017-04-14 17:23:06.918  INFO 16083 --- [           main] o.s.b.f.s.DefaultListableBeanFactory     : Overriding bean definition for bean 'transactionManager' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.cloud.task.configuration.SimpleTaskConfiguration; factoryMethodName=transactionManager; initMethodName=null; destroyMethodName=(inferred); defined in org.springframework.cloud.task.configuration.SimpleTaskConfiguration] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.batch.core.configuration.annotation.SimpleBatchConfiguration; factoryMethodName=transactionManager; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/batch/core/configuration/annotation/SimpleBatchConfiguration.class]]
14-Apr-2017 17:23:07 2017-04-14 17:23:07.555  WARN 16083 --- [           main] o.s.c.a.ConfigurationClassEnhancer       : @Bean method ScopeConfiguration.stepScope is non-static and returns an object assignable to Spring's BeanFactoryPostProcessor interface. This will result in a failure to process annotations such as @Autowired, @Resource and @PostConstruct within the method's declaring @Configuration class. Add the 'static' modifier to this method to avoid these container lifecycle issues; see @Bean javadoc for complete details.
14-Apr-2017 17:23:07 2017-04-14 17:23:07.569  WARN 16083 --- [           main] o.s.c.a.ConfigurationClassEnhancer       : @Bean method ScopeConfiguration.jobScope is non-static and returns an object assignable to Spring's BeanFactoryPostProcessor interface. This will result in a failure to process annotations such as @Autowired, @Resource and @PostConstruct within the method's declaring @Configuration class. Add the 'static' modifier to this method to avoid these container lifecycle issues; see @Bean javadoc for complete details.
14-Apr-2017 17:23:07 2017-04-14 17:23:07.578  INFO 16083 --- [           main] faultConfiguringBeanFactoryPostProcessor : No bean named 'errorChannel' has been explicitly defined. Therefore, a default PublishSubscribeChannel will be created.
14-Apr-2017 17:23:07 2017-04-14 17:23:07.581  INFO 16083 --- [           main] faultConfiguringBeanFactoryPostProcessor : No bean named 'taskScheduler' has been explicitly defined. Therefore, a default ThreadPoolTaskScheduler will be created.
14-Apr-2017 17:23:07 2017-04-14 17:23:07.761  INFO 16083 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.amqp.rabbit.annotation.RabbitBootstrapConfiguration' of type [org.springframework.amqp.rabbit.annotation.RabbitBootstrapConfiguration$$EnhancerBySpringCGLIB$$65a7453e] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:23:07 2017-04-14 17:23:07.802  INFO 16083 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.cloud.stream.config.BindingServiceConfiguration$PostProcessorConfiguration' of type [org.springframework.cloud.stream.config.BindingServiceConfiguration$PostProcessorConfiguration$$EnhancerBySpringCGLIB$$61032515] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:23:07 2017-04-14 17:23:07.815  INFO 16083 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration' of type [org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$9f3b8710] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:23:07 2017-04-14 17:23:07.935  INFO 16083 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'integrationGlobalProperties' of type [org.springframework.beans.factory.config.PropertiesFactoryBean] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:23:07 2017-04-14 17:23:07.937  INFO 16083 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'integrationGlobalProperties' of type [java.util.Properties] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:23:07 2017-04-14 17:23:07.980  INFO 16083 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.cloud.task.batch.configuration.TaskBatchAutoConfiguration' of type [org.springframework.cloud.task.batch.configuration.TaskBatchAutoConfiguration$$EnhancerBySpringCGLIB$$24ab329c] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:23:07 2017-04-14 17:23:07.992  INFO 16083 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration' of type [org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$$EnhancerBySpringCGLIB$$6d013c9f] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
14-Apr-2017 17:23:08 2017-04-14 17:23:08.357  INFO 16083 --- [           main] s.b.c.e.t.TomcatEmbeddedServletContainer : Tomcat initialized with port(s): 0 (http)
14-Apr-2017 17:23:08 2017-04-14 17:23:08.370  INFO 16083 --- [           main] o.apache.catalina.core.StandardService   : Starting service Tomcat
14-Apr-2017 17:23:08 2017-04-14 17:23:08.371  INFO 16083 --- [           main] org.apache.catalina.core.StandardEngine  : Starting Servlet Engine: Apache Tomcat/8.5.11
14-Apr-2017 17:23:08 2017-04-14 17:23:08.491  INFO 16083 --- [ost-startStop-1] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring embedded WebApplicationContext
14-Apr-2017 17:23:08 2017-04-14 17:23:08.492  INFO 16083 --- [ost-startStop-1] o.s.web.context.ContextLoader            : Root WebApplicationContext: initialization completed in 2453 ms
14-Apr-2017 17:23:08 2017-04-14 17:23:08.803  INFO 16083 --- [ost-startStop-1] o.s.b.w.servlet.ServletRegistrationBean  : Mapping servlet: 'dispatcherServlet' to [/]
14-Apr-2017 17:23:08 2017-04-14 17:23:08.807  INFO 16083 --- [ost-startStop-1] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'metricsFilter' to: [/*]
14-Apr-2017 17:23:08 2017-04-14 17:23:08.808  INFO 16083 --- [ost-startStop-1] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'characterEncodingFilter' to: [/*]
14-Apr-2017 17:23:08 2017-04-14 17:23:08.808  INFO 16083 --- [ost-startStop-1] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'hiddenHttpMethodFilter' to: [/*]
14-Apr-2017 17:23:08 2017-04-14 17:23:08.808  INFO 16083 --- [ost-startStop-1] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'httpPutFormContentFilter' to: [/*]
14-Apr-2017 17:23:08 2017-04-14 17:23:08.808  INFO 16083 --- [ost-startStop-1] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'requestContextFilter' to: [/*]
14-Apr-2017 17:23:08 2017-04-14 17:23:08.811  INFO 16083 --- [ost-startStop-1] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'webRequestLoggingFilter' to: [/*]
14-Apr-2017 17:23:08 2017-04-14 17:23:08.811  INFO 16083 --- [ost-startStop-1] o.s.b.w.servlet.FilterRegistrationBean   : Mapping filter: 'applicationContextIdFilter' to: [/*]
14-Apr-2017 17:23:09 2017-04-14 17:23:09.247 DEBUG 16083 --- [           main] s.c.s.c.CompositeMessageConverterFactory : Ommitted org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter@24db6ce of type class org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter for 'application/json' as it is not an AbstractMessageConverter
14-Apr-2017 17:23:09 2017-04-14 17:23:09.716  INFO 16083 --- [           main] o.s.s.c.ThreadPoolTaskScheduler          : Initializing ExecutorService  'taskScheduler'
14-Apr-2017 17:23:09 2017-04-14 17:23:09.793 DEBUG 16083 --- [           main] s.c.s.c.CompositeMessageConverterFactory : Ommitted org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter@24db6ce of type class org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter for 'application/json' as it is not an AbstractMessageConverter
14-Apr-2017 17:23:09 2017-04-14 17:23:09.801 DEBUG 16083 --- [           main] s.c.s.c.CompositeMessageConverterFactory : Ommitted org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter@24db6ce of type class org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter for 'application/json' as it is not an AbstractMessageConverter
14-Apr-2017 17:23:09 2017-04-14 17:23:09.801 DEBUG 16083 --- [           main] s.c.s.c.CompositeMessageConverterFactory : Ommitted org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter@24db6ce of type class org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter for 'application/json' as it is not an AbstractMessageConverter
14-Apr-2017 17:23:09 2017-04-14 17:23:09.801 DEBUG 16083 --- [           main] s.c.s.c.CompositeMessageConverterFactory : Ommitted org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter@24db6ce of type class org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter for 'application/json' as it is not an AbstractMessageConverter
14-Apr-2017 17:23:09 2017-04-14 17:23:09.802 DEBUG 16083 --- [           main] s.c.s.c.CompositeMessageConverterFactory : Ommitted org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter@24db6ce of type class org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter for 'application/json' as it is not an AbstractMessageConverter
14-Apr-2017 17:23:09 2017-04-14 17:23:09.803 DEBUG 16083 --- [           main] s.c.s.c.CompositeMessageConverterFactory : Ommitted org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter@24db6ce of type class org.springframework.integration.support.converter.DefaultDatatypeChannelMessageConverter for 'application/json' as it is not an AbstractMessageConverter
14-Apr-2017 17:23:10 2017-04-14 17:23:10.367 DEBUG 16083 --- [           main] o.s.c.t.c.SimpleTaskConfiguration        : Using org.springframework.cloud.task.configuration.DefaultTaskConfigurer TaskConfigurer
14-Apr-2017 17:23:10 2017-04-14 17:23:10.881 DEBUG 16083 --- [           main] o.s.c.t.r.s.TaskRepositoryInitializer    : Initializing task schema for hsqldb database
14-Apr-2017 17:23:10 2017-04-14 17:23:10.883  INFO 16083 --- [           main] o.s.jdbc.datasource.init.ScriptUtils     : Executing SQL script from class path resource [org/springframework/cloud/task/schema-hsqldb.sql]
14-Apr-2017 17:23:10 2017-04-14 17:23:10.888  INFO 16083 --- [           main] o.s.jdbc.datasource.init.ScriptUtils     : Executed SQL script from class path resource [org/springframework/cloud/task/schema-hsqldb.sql] in 5 ms.
14-Apr-2017 17:23:11 2017-04-14 17:23:11.204  INFO 16083 --- [           main] s.w.s.m.m.a.RequestMappingHandlerAdapter : Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@7a8c8dcf: startup date [Fri Apr 14 17:23:06 UTC 2017]; root of context hierarchy
14-Apr-2017 17:23:11 2017-04-14 17:23:11.285  INFO 16083 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error],produces=[text/html]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)
14-Apr-2017 17:23:11 2017-04-14 17:23:11.286  INFO 16083 --- [           main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/error]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
14-Apr-2017 17:23:11 2017-04-14 17:23:11.345  INFO 16083 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
14-Apr-2017 17:23:11 2017-04-14 17:23:11.345  INFO 16083 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
14-Apr-2017 17:23:11 2017-04-14 17:23:11.408  INFO 16083 --- [           main] o.s.w.s.handler.SimpleUrlHandlerMapping  : Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.055  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/loggers/{name:.*}],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.LoggersMvcEndpoint.get(java.lang.String)
14-Apr-2017 17:23:12 2017-04-14 17:23:12.055  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/loggers/{name:.*}],methods=[POST],consumes=[application/vnd.spring-boot.actuator.v1+json || application/json],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.LoggersMvcEndpoint.set(java.lang.String,java.util.Map<java.lang.String, java.lang.String>)
14-Apr-2017 17:23:12 2017-04-14 17:23:12.056  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/loggers || /loggers.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.056  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/mappings || /mappings.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.057  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/heapdump || /heapdump.json],methods=[GET],produces=[application/octet-stream]}" onto public void org.springframework.boot.actuate.endpoint.mvc.HeapdumpMvcEndpoint.invoke(boolean,javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse) throws java.io.IOException,javax.servlet.ServletException
14-Apr-2017 17:23:12 2017-04-14 17:23:12.058  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/metrics/{name:.*}],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.MetricsMvcEndpoint.value(java.lang.String)
14-Apr-2017 17:23:12 2017-04-14 17:23:12.058  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/metrics || /metrics.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.059  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/info || /info.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.062  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/env/{name:.*}],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EnvironmentMvcEndpoint.value(java.lang.String)
14-Apr-2017 17:23:12 2017-04-14 17:23:12.062  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/env || /env.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.063  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/trace || /trace.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.064  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/health || /health.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.HealthMvcEndpoint.invoke(javax.servlet.http.HttpServletRequest,java.security.Principal)
14-Apr-2017 17:23:12 2017-04-14 17:23:12.064  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/auditevents || /auditevents.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public org.springframework.http.ResponseEntity<?> org.springframework.boot.actuate.endpoint.mvc.AuditEventsMvcEndpoint.findByPrincipalAndAfterAndType(java.lang.String,java.util.Date,java.lang.String)
14-Apr-2017 17:23:12 2017-04-14 17:23:12.065  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/dump || /dump.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.066  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/beans || /beans.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.066  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/autoconfig || /autoconfig.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.067  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/channels || /channels.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.067  INFO 16083 --- [           main] o.s.b.a.e.mvc.EndpointHandlerMapping     : Mapped "{[/configprops || /configprops.json],methods=[GET],produces=[application/vnd.spring-boot.actuator.v1+json || application/json]}" onto public java.lang.Object org.springframework.boot.actuate.endpoint.mvc.EndpointMvcAdapter.invoke()
14-Apr-2017 17:23:12 2017-04-14 17:23:12.157  INFO 16083 --- [           main] o.s.jdbc.datasource.init.ScriptUtils     : Executing SQL script from class path resource [org/springframework/batch/core/schema-hsqldb.sql]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.164  INFO 16083 --- [           main] o.s.jdbc.datasource.init.ScriptUtils     : Executed SQL script from class path resource [org/springframework/batch/core/schema-hsqldb.sql] in 7 ms.
14-Apr-2017 17:23:12 2017-04-14 17:23:12.320  INFO 16083 --- [           main] o.s.i.codec.kryo.CompositeKryoRegistrar  : configured Kryo registration [40, java.io.File] with serializer org.springframework.integration.codec.kryo.FileSerializer
14-Apr-2017 17:23:12 2017-04-14 17:23:12.539  INFO 16083 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'batchEvents:0.input' has 1 subscriber(s).
14-Apr-2017 17:23:12 2017-04-14 17:23:12.540 DEBUG 16083 --- [           main] .s.i.c.GlobalChannelInterceptorProcessor : No global channel interceptors.
14-Apr-2017 17:23:12 2017-04-14 17:23:12.542  INFO 16083 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Registering beans for JMX exposure on startup
14-Apr-2017 17:23:12 2017-04-14 17:23:12.551  INFO 16083 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Bean with name 'rabbitConnectionFactory' has been autodetected for JMX exposure
14-Apr-2017 17:23:12 2017-04-14 17:23:12.552  INFO 16083 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Bean with name 'integrationMbeanExporter' has been autodetected for JMX exposure
14-Apr-2017 17:23:12 2017-04-14 17:23:12.555  INFO 16083 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Located managed bean 'integrationMbeanExporter': registering with JMX server as MBean [org.springframework.integration.monitor:name=integrationMbeanExporter,type=IntegrationMBeanExporter]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.596  INFO 16083 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Located managed bean 'rabbitConnectionFactory': registering with JMX server as MBean [org.springframework.amqp.rabbit.connection:name=rabbitConnectionFactory,type=CachingConnectionFactory]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.608  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering beans for JMX exposure on startup
14-Apr-2017 17:23:12 2017-04-14 17:23:12.608  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel skip-events
14-Apr-2017 17:23:12 2017-04-14 17:23:12.612  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=skip-events': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=skip-events]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.649  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel input
14-Apr-2017 17:23:12 2017-04-14 17:23:12.651  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=input': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=input]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.667  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel step-execution-events
14-Apr-2017 17:23:12 2017-04-14 17:23:12.668  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=step-execution-events': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=step-execution-events]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.685  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel nullChannel
14-Apr-2017 17:23:12 2017-04-14 17:23:12.687  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=nullChannel': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=nullChannel]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.698  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel errorChannel
14-Apr-2017 17:23:12 2017-04-14 17:23:12.699  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=errorChannel': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=errorChannel]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.725  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel item-read-events
14-Apr-2017 17:23:12 2017-04-14 17:23:12.730  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=item-read-events': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=item-read-events]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.744  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel item-write-events
14-Apr-2017 17:23:12 2017-04-14 17:23:12.746  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=item-write-events': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=item-write-events]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.760  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel item-process-events
14-Apr-2017 17:23:12 2017-04-14 17:23:12.761  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=item-process-events': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=item-process-events]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.775  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel chunk-events
14-Apr-2017 17:23:12 2017-04-14 17:23:12.776  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=chunk-events': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=chunk-events]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.790  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel task-events
14-Apr-2017 17:23:12 2017-04-14 17:23:12.791  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=task-events': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=task-events]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.804  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageChannel job-execution-events
14-Apr-2017 17:23:12 2017-04-14 17:23:12.806  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageChannel,name=job-execution-events': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=job-execution-events]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.821 DEBUG 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Getting field [handler] from target [_org.springframework.integration.errorLogger]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.822  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registering MessageHandler errorLogger
14-Apr-2017 17:23:12 2017-04-14 17:23:12.823  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=MessageHandler,name=errorLogger,bean=internal': registering with JMX server as MBean [org.springframework.integration:type=MessageHandler,name=errorLogger,bean=internal]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.841  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Located managed bean 'org.springframework.integration:type=ManagedEndpoint,name=taskEventListener,bean=endpoint': registering with JMX server as MBean [org.springframework.integration:type=ManagedEndpoint,name=taskEventListener,bean=endpoint]
14-Apr-2017 17:23:12 2017-04-14 17:23:12.844  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Registered endpoint without MessageSource: org.springframework.integration:type=ManagedEndpoint,name=taskEventListener,bean=endpoint
14-Apr-2017 17:23:12 2017-04-14 17:23:12.850  INFO 16083 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase -2147482648
14-Apr-2017 17:23:12 2017-04-14 17:23:12.852 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding outputs for :interface org.springframework.cloud.stream.messaging.Sink
14-Apr-2017 17:23:12 2017-04-14 17:23:12.852 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding outputs for :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels
14-Apr-2017 17:23:12 2017-04-14 17:23:12.852 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:item-write-events
14-Apr-2017 17:23:12 2017-04-14 17:23:12.918  INFO 16083 --- [           main] o.a.maven.surefire.booter.ForkedBooter   : No active profile set, falling back to default profiles: default
14-Apr-2017 17:23:12 2017-04-14 17:23:12.921  INFO 16083 --- [           main] s.c.a.AnnotationConfigApplicationContext : Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@3a9b41a0: startup date [Fri Apr 14 17:23:12 UTC 2017]; parent: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@7a8c8dcf
14-Apr-2017 17:23:13 2017-04-14 17:23:13.020  INFO 16083 --- [           main] o.s.c.support.GenericApplicationContext  : Refreshing org.springframework.context.support.GenericApplicationContext@38de5daf: startup date [Fri Apr 14 17:23:13 UTC 2017]; root of context hierarchy
14-Apr-2017 17:23:13 2017-04-14 17:23:13.054 ERROR 16083 --- [           main] o.s.c.t.listener.TaskLifecycleListener   : An event to end a task has been received for a task that has not yet started.
14-Apr-2017 17:23:13 2017-04-14 17:23:13.055  INFO 16083 --- [           main] o.a.maven.surefire.booter.ForkedBooter   : Started ForkedBooter in 0.185 seconds (JVM running for 8.082)
14-Apr-2017 17:23:13 2017-04-14 17:23:13.128  INFO 16083 --- [           main] o.s.a.r.c.CachingConnectionFactory       : Created new connection: SimpleConnection@68e47e7 [delegate=amqp://guest@127.0.0.1:5672/, localPort= 34720]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.161  INFO 16083 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'batchEvents:0.item-write-events' has 1 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.162 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:skip-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.165  INFO 16083 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'batchEvents:0.skip-events' has 1 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.165 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:job-execution-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.168  INFO 16083 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'batchEvents:0.job-execution-events' has 1 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.168 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:item-process-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.171  INFO 16083 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'batchEvents:0.item-process-events' has 1 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.171 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:step-execution-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.173  INFO 16083 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'batchEvents:0.step-execution-events' has 1 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.173 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:chunk-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.174  INFO 16083 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'batchEvents:0.chunk-events' has 1 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.175 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:item-read-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.176  INFO 16083 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'batchEvents:0.item-read-events' has 1 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.176 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding outputs for :interface org.springframework.cloud.task.listener.TaskEventAutoConfiguration$TaskEventChannels
14-Apr-2017 17:23:13 2017-04-14 17:23:13.177 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.listener.TaskEventAutoConfiguration$TaskEventChannels:task-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.178  INFO 16083 --- [           main] o.s.integration.channel.DirectChannel    : Channel 'batchEvents:0.task-events' has 1 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.178  INFO 16083 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 0
14-Apr-2017 17:23:13 2017-04-14 17:23:13.178  INFO 16083 --- [           main] ProxyFactoryBean$MethodInvocationGateway : started taskEventListener
14-Apr-2017 17:23:13 2017-04-14 17:23:13.178  INFO 16083 --- [           main] ProxyFactoryBean$MethodInvocationGateway : started taskEventListener
14-Apr-2017 17:23:13 2017-04-14 17:23:13.179  INFO 16083 --- [           main] ProxyFactoryBean$MethodInvocationGateway : started taskEventListener
14-Apr-2017 17:23:13 2017-04-14 17:23:13.179  INFO 16083 --- [           main] o.s.i.gateway.GatewayProxyFactoryBean    : started taskEventListener
14-Apr-2017 17:23:13 2017-04-14 17:23:13.196 DEBUG 16083 --- [           main] o.s.c.t.r.support.SimpleTaskRepository   : Creating: TaskExecution{executionId=0, parentExecutionId=null, exitCode=null, taskName='batchEvents_0', startTime=Fri Apr 14 17:23:13 UTC 2017, endTime=null, exitMessage='null', externalExecutionId='null', errorMessage='null', arguments=[--server.port=0, --spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup, --logging.level.org.springframework.integration=DEBUG]}
14-Apr-2017 17:23:13 2017-04-14 17:23:13.202 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'task-events', message: GenericMessage [payload=TaskExecution{executionId=0, parentExecutionId=null, exitCode=0, taskName='batchEvents_0', startTime=Fri Apr 14 17:23:13 UTC 2017, endTime=null, exitMessage='null', externalExecutionId='null', errorMessage='null', arguments=[--server.port=0, --spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup, --logging.level.org.springframework.integration=DEBUG]}, headers={id=b0478665-e52d-dac2-cdaf-058af829f6f8, timestamp=1492190593202}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.252 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@7d284f15 received message: GenericMessage [payload={"executionId":0,"parentExecutionId":null,"exitCode":0,"taskName":"batchEvents_0","startTime":1492190593179,"endTime":null,"exitMessage":null,"externalExecutionId":null,"errorMessage":null,"arguments":["--server.port=0","--spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup","--logging.level.org.springframework.integration=DEBUG"]}, headers={contentType=application/json;charset=UTF-8, id=c6e5d92e-44ec-6e83-fd85-957deedab3c8, timestamp=1492190593252}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.253 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@3303e89e received message: GenericMessage [payload=byte[351], headers={id=d2f2240f-3484-5b10-6ca8-7c211cf00edb, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593253}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.256 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.256 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[originalContentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.259 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@3303e89e' produced no reply for request Message: GenericMessage [payload=byte[351], headers={id=d2f2240f-3484-5b10-6ca8-7c211cf00edb, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593253}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.260 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'task-events', message: GenericMessage [payload={"executionId":0,"parentExecutionId":null,"exitCode":0,"taskName":"batchEvents_0","startTime":1492190593179,"endTime":null,"exitMessage":null,"externalExecutionId":null,"errorMessage":null,"arguments":["--server.port=0","--spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup","--logging.level.org.springframework.integration=DEBUG"]}, headers={contentType=application/json;charset=UTF-8, id=c6e5d92e-44ec-6e83-fd85-957deedab3c8, timestamp=1492190593252}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.299  INFO 16083 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : Adding {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
14-Apr-2017 17:23:13 2017-04-14 17:23:13.299  INFO 16083 --- [           main] o.s.i.channel.PublishSubscribeChannel    : Channel 'batchEvents:0.errorChannel' has 1 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.299  INFO 16083 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : started _org.springframework.integration.errorLogger
14-Apr-2017 17:23:13 2017-04-14 17:23:13.299  INFO 16083 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 2147482647
14-Apr-2017 17:23:13 2017-04-14 17:23:13.299 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding inputs for :interface org.springframework.cloud.stream.messaging.Sink
14-Apr-2017 17:23:13 2017-04-14 17:23:13.299 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.stream.messaging.Sink:input
14-Apr-2017 17:23:13 2017-04-14 17:23:13.312  INFO 16083 --- [           main] c.s.b.r.p.RabbitExchangeQueueProvisioner : declaring queue for inbound: job-execution-events.testgroup, bound to: job-execution-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.335 DEBUG 16083 --- [           main] c.s.b.r.p.RabbitExchangeQueueProvisioner : autoBindDLQ=false for: job-execution-events.testgroup
14-Apr-2017 17:23:13 2017-04-14 17:23:13.385  INFO 16083 --- [           main] o.s.i.a.i.AmqpInboundChannelAdapter      : started inbound.job-execution-events.testgroup
14-Apr-2017 17:23:13 2017-04-14 17:23:13.385  INFO 16083 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : Adding {message-handler:inbound.job-execution-events.testgroup} as a subscriber to the 'bridge.job-execution-events' channel
14-Apr-2017 17:23:13 2017-04-14 17:23:13.385  INFO 16083 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : started inbound.job-execution-events.testgroup
14-Apr-2017 17:23:13 2017-04-14 17:23:13.386 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding inputs for :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels
14-Apr-2017 17:23:13 2017-04-14 17:23:13.386 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding inputs for :interface org.springframework.cloud.task.listener.TaskEventAutoConfiguration$TaskEventChannels
14-Apr-2017 17:23:13 2017-04-14 17:23:13.386  INFO 16083 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Starting beans in phase 2147483647
14-Apr-2017 17:23:13 2017-04-14 17:23:13.445  INFO 16083 --- [           main] s.b.c.e.t.TomcatEmbeddedServletContainer : Tomcat started on port(s): 42295 (http)
14-Apr-2017 17:23:13 2017-04-14 17:23:13.448  INFO 16083 --- [           main] o.s.b.a.b.JobLauncherCommandLineRunner   : Running default command line with: [--server.port=0, --spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup, --logging.level.org.springframework.integration=DEBUG]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.457  INFO 16083 --- [           main] o.s.b.c.r.s.JobRepositoryFactoryBean     : No database type set, using meta data indicating: HSQL
14-Apr-2017 17:23:13 2017-04-14 17:23:13.554  INFO 16083 --- [           main] o.s.b.c.l.support.SimpleJobLauncher      : No TaskExecutor has been set, defaulting to synchronous executor.
14-Apr-2017 17:23:13 2017-04-14 17:23:13.587  INFO 16083 --- [           main] o.s.b.c.l.support.SimpleJobLauncher      : Job: [SimpleJob: [name=job]] launched with the following parameters: [{-server.port=0, -logging.level.org.springframework.integration=DEBUG, -spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.597 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'job-execution-events', message: GenericMessage [payload=JobExecutionEvent: id=0, version=1, startTime=Fri Apr 14 17:23:13 UTC 2017, endTime=null, lastUpdated=Fri Apr 14 17:23:13 UTC 2017, status=STARTED, exitStatus=org.springframework.cloud.task.batch.listener.support.ExitStatus@5b1ff8cd, job=[JobInstanceEvent: id=0, version=null, Job=[job]], jobParameters=[{-server.port=0, -logging.level.org.springframework.integration=DEBUG, -spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup}], headers={id=4680c061-4172-89cb-2962-ec6c1c7d1259, timestamp=1492190593596}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.612 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@1884e671 received message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[],"status":"STARTED","startTime":1492190593589,"createTime":1492190593567,"endTime":null,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"UNKNOWN","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={contentType=application/json;charset=UTF-8, id=85a3c480-4961-6259-e630-257be579761a, timestamp=1492190593612}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.612 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@1c7843c3 received message: GenericMessage [payload=byte[752], headers={id=cdfbf2ee-7973-baaa-49e8-f6cfda13d670, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593612}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.612 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.612 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[originalContentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.614 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@1c7843c3' produced no reply for request Message: GenericMessage [payload=byte[752], headers={id=cdfbf2ee-7973-baaa-49e8-f6cfda13d670, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593612}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.614 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'job-execution-events', message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[],"status":"STARTED","startTime":1492190593589,"createTime":1492190593567,"endTime":null,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"UNKNOWN","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={contentType=application/json;charset=UTF-8, id=85a3c480-4961-6259-e630-257be579761a, timestamp=1492190593612}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.615  INFO 16083 --- [           main] o.s.c.t.b.l.TaskBatchExecutionListener   : The job execution id 0 was run within the task execution 0
14-Apr-2017 17:23:13 2017-04-14 17:23:13.625  INFO 16083 --- [           main] o.s.batch.core.job.SimpleStepHandler     : Executing step: [step1]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.628 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'step-execution-events', message: GenericMessage [payload=StepExecutionEvent: id=0, version=null, name=step1, status=STARTED, exitStatus=EXECUTING, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=0, rollbackCount=0, exitDescription=, headers={id=7e843e33-9a80-760b-0972-cb79a10632a2, timestamp=1492190593628}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.631 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@563843f1 received message: GenericMessage [payload={"id":0,"version":null,"jobExecutionId":0,"stepName":"step1","status":"STARTED","readCount":0,"writeCount":0,"commitCount":0,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593625,"endTime":null,"lastUpdated":1492190593625,"executionContext":{"dirty":false,"empty":true},"exitStatus":{"exitCode":"EXECUTING","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=0, version=null, name=step1, status=STARTED, exitStatus=EXECUTING, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=0, rollbackCount=0","skipCount":0}, headers={contentType=application/json;charset=UTF-8, id=4ef17ad6-c923-2e60-2edd-38271e2f2f00, timestamp=1492190593631}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.632 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@38cb1606 received message: GenericMessage [payload=byte[681], headers={id=1cd094b7-56a4-aaab-7961-7ee05a92f0f8, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593631}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.632 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.632 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[originalContentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.632 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@38cb1606' produced no reply for request Message: GenericMessage [payload=byte[681], headers={id=1cd094b7-56a4-aaab-7961-7ee05a92f0f8, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593631}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.632 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'step-execution-events', message: GenericMessage [payload={"id":0,"version":null,"jobExecutionId":0,"stepName":"step1","status":"STARTED","readCount":0,"writeCount":0,"commitCount":0,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593625,"endTime":null,"lastUpdated":1492190593625,"executionContext":{"dirty":false,"empty":true},"exitStatus":{"exitCode":"EXECUTING","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=0, version=null, name=step1, status=STARTED, exitStatus=EXECUTING, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=0, rollbackCount=0","skipCount":0}, headers={contentType=application/json;charset=UTF-8, id=4ef17ad6-c923-2e60-2edd-38271e2f2f00, timestamp=1492190593631}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.640 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'chunk-events', message: GenericMessage [payload=Before Chunk Processing, headers={id=b9d19fa5-3de1-9a33-3416-53a81cb0e06a, timestamp=1492190593640}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.640 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@5d5574c7 received message: GenericMessage [payload=Before Chunk Processing, headers={id=b9d19fa5-3de1-9a33-3416-53a81cb0e06a, timestamp=1492190593640}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.641 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34 received message: GenericMessage [payload=byte[23], headers={contentType=text/plain, id=53aed5ad-4c97-fe5d-84db-7ab61c90fb10, timestamp=1492190593641}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.641 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.641 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34' produced no reply for request Message: GenericMessage [payload=byte[23], headers={contentType=text/plain, id=53aed5ad-4c97-fe5d-84db-7ab61c90fb10, timestamp=1492190593641}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.642 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'chunk-events', message: GenericMessage [payload=Before Chunk Processing, headers={id=b9d19fa5-3de1-9a33-3416-53a81cb0e06a, timestamp=1492190593640}]
14-Apr-2017 17:23:13 Tasklet has run
14-Apr-2017 17:23:13 2017-04-14 17:23:13.646 DEBUG 16083 --- [nts.testgroup-1] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[amqp_receivedDeliveryMode] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.646 DEBUG 16083 --- [nts.testgroup-1] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[amqp_receivedRoutingKey] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.646 DEBUG 16083 --- [nts.testgroup-1] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[amqp_receivedExchange] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.646 DEBUG 16083 --- [nts.testgroup-1] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[amqp_deliveryTag] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.646 DEBUG 16083 --- [nts.testgroup-1] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[amqp_redelivered] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.646 DEBUG 16083 --- [nts.testgroup-1] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.647 DEBUG 16083 --- [nts.testgroup-1] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.647 DEBUG 16083 --- [nts.testgroup-1] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[originalContentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.647 DEBUG 16083 --- [nts.testgroup-1] actMessageChannelBinder$ReceivingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$ReceivingHandler@37f7de83 received message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[],"status":"STARTED","startTime":1492190593589,"createTime":1492190593567,"endTime":null,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"UNKNOWN","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={amqp_receivedDeliveryMode=PERSISTENT, amqp_receivedRoutingKey=job-execution-events, amqp_receivedExchange=job-execution-events, amqp_deliveryTag=1, amqp_consumerQueue=job-execution-events.testgroup, amqp_redelivered=false, id=a43770dc-b198-c5e9-bf71-9ba3d0a18315, amqp_consumerTag=amq.ctag-75g62_Klg26u4_38U6jdSA, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593647}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.647 DEBUG 16083 --- [nts.testgroup-1] o.s.integration.channel.DirectChannel    : preSend on channel 'input', message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[],"status":"STARTED","startTime":1492190593589,"createTime":1492190593567,"endTime":null,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"UNKNOWN","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={amqp_receivedDeliveryMode=PERSISTENT, amqp_receivedRoutingKey=job-execution-events, amqp_receivedExchange=job-execution-events, amqp_deliveryTag=1, amqp_consumerQueue=job-execution-events.testgroup, amqp_redelivered=false, id=523fa89d-89bd-855a-76e0-48dc213187a2, amqp_consumerTag=amq.ctag-75g62_Klg26u4_38U6jdSA, contentType=application/json;charset=UTF-8, timestamp=1492190593647}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.647 DEBUG 16083 --- [nts.testgroup-1] .DispatchingStreamListenerMessageHandler : org.springframework.cloud.stream.binding.DispatchingStreamListenerMessageHandler@64c27b67 received message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[],"status":"STARTED","startTime":1492190593589,"createTime":1492190593567,"endTime":null,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"UNKNOWN","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={amqp_receivedDeliveryMode=PERSISTENT, amqp_receivedRoutingKey=job-execution-events, amqp_receivedExchange=job-execution-events, amqp_deliveryTag=1, amqp_consumerQueue=job-execution-events.testgroup, amqp_redelivered=false, id=523fa89d-89bd-855a-76e0-48dc213187a2, amqp_consumerTag=amq.ctag-75g62_Klg26u4_38U6jdSA, contentType=application/json;charset=UTF-8, timestamp=1492190593647}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.648 DEBUG 16083 --- [nts.testgroup-1] o.s.c.s.b.StreamListenerMessageHandler   : org.springframework.cloud.stream.binding.StreamListenerMessageHandler@5c0ed5ea received message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[],"status":"STARTED","startTime":1492190593589,"createTime":1492190593567,"endTime":null,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"UNKNOWN","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={amqp_receivedDeliveryMode=PERSISTENT, amqp_receivedRoutingKey=job-execution-events, amqp_receivedExchange=job-execution-events, amqp_deliveryTag=1, amqp_consumerQueue=job-execution-events.testgroup, amqp_redelivered=false, id=523fa89d-89bd-855a-76e0-48dc213187a2, amqp_consumerTag=amq.ctag-75g62_Klg26u4_38U6jdSA, contentType=application/json;charset=UTF-8, timestamp=1492190593647}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.653 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'chunk-events', message: GenericMessage [payload=After Chunk Processing, headers={id=99ff51db-b6c9-46c9-b5c9-c26f62f2fbbb, timestamp=1492190593653}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.653 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@5d5574c7 received message: GenericMessage [payload=After Chunk Processing, headers={id=99ff51db-b6c9-46c9-b5c9-c26f62f2fbbb, timestamp=1492190593653}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.653 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34 received message: GenericMessage [payload=byte[22], headers={contentType=text/plain, id=27ea4437-f55b-c678-892d-1df87dd9726e, timestamp=1492190593653}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.653 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.654 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34' produced no reply for request Message: GenericMessage [payload=byte[22], headers={contentType=text/plain, id=27ea4437-f55b-c678-892d-1df87dd9726e, timestamp=1492190593653}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.654 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'chunk-events', message: GenericMessage [payload=After Chunk Processing, headers={id=99ff51db-b6c9-46c9-b5c9-c26f62f2fbbb, timestamp=1492190593653}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.654 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'step-execution-events', message: GenericMessage [payload=StepExecutionEvent: id=0, version=null, name=step1, status=COMPLETED, exitStatus=COMPLETED, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=1, rollbackCount=0, exitDescription=, headers={id=460137fd-2e3a-2347-c261-331c6fb0d5ce, timestamp=1492190593654}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.655 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@563843f1 received message: GenericMessage [payload={"id":0,"version":null,"jobExecutionId":0,"stepName":"step1","status":"COMPLETED","readCount":0,"writeCount":0,"commitCount":1,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593625,"endTime":null,"lastUpdated":1492190593651,"executionContext":{"dirty":true,"empty":false},"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=0, version=null, name=step1, status=COMPLETED, exitStatus=COMPLETED, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=1, rollbackCount=0","skipCount":0}, headers={contentType=application/json;charset=UTF-8, id=fecf4b82-d3ec-266a-d4db-42b687253531, timestamp=1492190593655}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.655 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@38cb1606 received message: GenericMessage [payload=byte[685], headers={id=f2a02e98-6629-b2ad-881b-f71f851569ed, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593655}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.656 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.656 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[originalContentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.656 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@38cb1606' produced no reply for request Message: GenericMessage [payload=byte[685], headers={id=f2a02e98-6629-b2ad-881b-f71f851569ed, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593655}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.656 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'step-execution-events', message: GenericMessage [payload={"id":0,"version":null,"jobExecutionId":0,"stepName":"step1","status":"COMPLETED","readCount":0,"writeCount":0,"commitCount":1,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593625,"endTime":null,"lastUpdated":1492190593651,"executionContext":{"dirty":true,"empty":false},"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=0, version=null, name=step1, status=COMPLETED, exitStatus=COMPLETED, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=1, rollbackCount=0","skipCount":0}, headers={contentType=application/json;charset=UTF-8, id=fecf4b82-d3ec-266a-d4db-42b687253531, timestamp=1492190593655}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.666  INFO 16083 --- [           main] o.s.batch.core.job.SimpleStepHandler     : Executing step: [step2]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.669 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'step-execution-events', message: GenericMessage [payload=StepExecutionEvent: id=1, version=null, name=step2, status=STARTED, exitStatus=EXECUTING, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=0, rollbackCount=0, exitDescription=, headers={id=360b8eac-ae67-3839-9f3c-fe11b0c0bf29, timestamp=1492190593669}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.669 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@563843f1 received message: GenericMessage [payload={"id":1,"version":null,"jobExecutionId":0,"stepName":"step2","status":"STARTED","readCount":0,"writeCount":0,"commitCount":0,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593666,"endTime":null,"lastUpdated":1492190593667,"executionContext":{"dirty":false,"empty":true},"exitStatus":{"exitCode":"EXECUTING","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=1, version=null, name=step2, status=STARTED, exitStatus=EXECUTING, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=0, rollbackCount=0","skipCount":0}, headers={contentType=application/json;charset=UTF-8, id=093faced-a04a-5e5d-1900-c289e1d0c77e, timestamp=1492190593669}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.670 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@38cb1606 received message: GenericMessage [payload=byte[681], headers={id=e5cfdd81-7dd3-55c0-9a68-0e3ad9cba662, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593670}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.670 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.670 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[originalContentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.678 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@38cb1606' produced no reply for request Message: GenericMessage [payload=byte[681], headers={id=e5cfdd81-7dd3-55c0-9a68-0e3ad9cba662, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593670}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.678 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'step-execution-events', message: GenericMessage [payload={"id":1,"version":null,"jobExecutionId":0,"stepName":"step2","status":"STARTED","readCount":0,"writeCount":0,"commitCount":0,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593666,"endTime":null,"lastUpdated":1492190593667,"executionContext":{"dirty":false,"empty":true},"exitStatus":{"exitCode":"EXECUTING","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=1, version=null, name=step2, status=STARTED, exitStatus=EXECUTING, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=0, rollbackCount=0","skipCount":0}, headers={contentType=application/json;charset=UTF-8, id=093faced-a04a-5e5d-1900-c289e1d0c77e, timestamp=1492190593669}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.687 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'chunk-events', message: GenericMessage [payload=Before Chunk Processing, headers={id=b554d1cf-dc35-71bd-ff3d-109c880f45b5, timestamp=1492190593686}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.687 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@5d5574c7 received message: GenericMessage [payload=Before Chunk Processing, headers={id=b554d1cf-dc35-71bd-ff3d-109c880f45b5, timestamp=1492190593686}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.698 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34 received message: GenericMessage [payload=byte[23], headers={contentType=text/plain, id=c307d8d6-4349-f137-b4d0-943e17af6dbe, timestamp=1492190593698}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.698 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.699 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34' produced no reply for request Message: GenericMessage [payload=byte[23], headers={contentType=text/plain, id=c307d8d6-4349-f137-b4d0-943e17af6dbe, timestamp=1492190593698}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.699 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'chunk-events', message: GenericMessage [payload=Before Chunk Processing, headers={id=b554d1cf-dc35-71bd-ff3d-109c880f45b5, timestamp=1492190593686}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.701 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-process-events', message: GenericMessage [payload=item did not equal result after processing, headers={id=1c933d15-6b00-b4de-4fde-560b05fb6311, timestamp=1492190593701}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.702 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@67a6a2d0 received message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=1c933d15-6b00-b4de-4fde-560b05fb6311, timestamp=1492190593701}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.703 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123 received message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=442fcf64-a73e-cc42-ccdd-97272c01b646, timestamp=1492190593703}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.703 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.703 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123' produced no reply for request Message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=442fcf64-a73e-cc42-ccdd-97272c01b646, timestamp=1492190593703}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.703 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-process-events', message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=1c933d15-6b00-b4de-4fde-560b05fb6311, timestamp=1492190593701}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.703 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-process-events', message: GenericMessage [payload=item did not equal result after processing, headers={id=3c6b53d7-2df0-bf40-9e18-c3842e9770d9, timestamp=1492190593703}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.704 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@67a6a2d0 received message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=3c6b53d7-2df0-bf40-9e18-c3842e9770d9, timestamp=1492190593703}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.704 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123 received message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=4b02254f-5053-662f-9c95-31c0391698a5, timestamp=1492190593704}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.704 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.704 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123' produced no reply for request Message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=4b02254f-5053-662f-9c95-31c0391698a5, timestamp=1492190593704}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.704 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-process-events', message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=3c6b53d7-2df0-bf40-9e18-c3842e9770d9, timestamp=1492190593703}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.705 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-process-events', message: GenericMessage [payload=item did not equal result after processing, headers={id=a44e01c8-3a71-8694-2f60-e57f127148f0, timestamp=1492190593705}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.705 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@67a6a2d0 received message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=a44e01c8-3a71-8694-2f60-e57f127148f0, timestamp=1492190593705}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.705 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123 received message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=1cd7c23d-6d5b-aac3-ee8c-c96a3d42a9b1, timestamp=1492190593705}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.705 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.705 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123' produced no reply for request Message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=1cd7c23d-6d5b-aac3-ee8c-c96a3d42a9b1, timestamp=1492190593705}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.705 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-process-events', message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=a44e01c8-3a71-8694-2f60-e57f127148f0, timestamp=1492190593705}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.706 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-write-events', message: GenericMessage [payload=3 items to be written., headers={id=5ea73b95-0687-4dbe-fe14-3e09cff9e7f4, timestamp=1492190593706}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.706 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@2e85e3f1 received message: [Payload String content=3 items to be written.][Headers={contentType=application/json, id=5ea73b95-0687-4dbe-fe14-3e09cff9e7f4, timestamp=1492190593706}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.706 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@195498aa received message: GenericMessage [payload=byte[22], headers={contentType=application/json, id=afea2434-918a-263e-28ee-e2ad43ee3215, timestamp=1492190593706}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.706 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.707 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@195498aa' produced no reply for request Message: GenericMessage [payload=byte[22], headers={contentType=application/json, id=afea2434-918a-263e-28ee-e2ad43ee3215, timestamp=1492190593706}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.707 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-write-events', message: [Payload String content=3 items to be written.][Headers={contentType=application/json, id=5ea73b95-0687-4dbe-fe14-3e09cff9e7f4, timestamp=1492190593706}]
14-Apr-2017 17:23:13 >> -1
14-Apr-2017 17:23:13 >> -2
14-Apr-2017 17:23:13 >> -3
14-Apr-2017 17:23:13 2017-04-14 17:23:13.707 DEBUG 16083 --- [           main] s.c.t.b.l.EventEmittingItemWriteListener : Executing afterWrite: [-1, -2, -3]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.707 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-write-events', message: GenericMessage [payload=3 items have been written., headers={id=0b57b7c7-7e1d-c27b-2a82-42e8bcb80d25, timestamp=1492190593707}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.708 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@2e85e3f1 received message: [Payload String content=3 items have been written.][Headers={contentType=application/json, id=0b57b7c7-7e1d-c27b-2a82-42e8bcb80d25, timestamp=1492190593707}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.708 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@195498aa received message: GenericMessage [payload=byte[26], headers={contentType=application/json, id=1a76548d-b09a-56b6-987a-054bb56da280, timestamp=1492190593708}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.708 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.709 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@195498aa' produced no reply for request Message: GenericMessage [payload=byte[26], headers={contentType=application/json, id=1a76548d-b09a-56b6-987a-054bb56da280, timestamp=1492190593708}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.709 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-write-events', message: [Payload String content=3 items have been written.][Headers={contentType=application/json, id=0b57b7c7-7e1d-c27b-2a82-42e8bcb80d25, timestamp=1492190593707}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.712 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'chunk-events', message: GenericMessage [payload=After Chunk Processing, headers={id=0c37082f-c523-ac24-fb27-61298a9aa4a6, timestamp=1492190593712}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.712 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@5d5574c7 received message: GenericMessage [payload=After Chunk Processing, headers={id=0c37082f-c523-ac24-fb27-61298a9aa4a6, timestamp=1492190593712}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.712 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34 received message: GenericMessage [payload=byte[22], headers={contentType=text/plain, id=05fc1ff8-4211-ce67-fa9a-669bd9003370, timestamp=1492190593712}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.712 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.713 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34' produced no reply for request Message: GenericMessage [payload=byte[22], headers={contentType=text/plain, id=05fc1ff8-4211-ce67-fa9a-669bd9003370, timestamp=1492190593712}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.713 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'chunk-events', message: GenericMessage [payload=After Chunk Processing, headers={id=0c37082f-c523-ac24-fb27-61298a9aa4a6, timestamp=1492190593712}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.713 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'chunk-events', message: GenericMessage [payload=Before Chunk Processing, headers={id=3d88cdb7-5e02-a271-018a-04dbff946bfb, timestamp=1492190593713}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.713 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@5d5574c7 received message: GenericMessage [payload=Before Chunk Processing, headers={id=3d88cdb7-5e02-a271-018a-04dbff946bfb, timestamp=1492190593713}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.713 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34 received message: GenericMessage [payload=byte[23], headers={contentType=text/plain, id=f3603b05-2d6a-40dc-31a8-9dd79cd58363, timestamp=1492190593713}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.714 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.714 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34' produced no reply for request Message: GenericMessage [payload=byte[23], headers={contentType=text/plain, id=f3603b05-2d6a-40dc-31a8-9dd79cd58363, timestamp=1492190593713}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.714 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'chunk-events', message: GenericMessage [payload=Before Chunk Processing, headers={id=3d88cdb7-5e02-a271-018a-04dbff946bfb, timestamp=1492190593713}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.714 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-process-events', message: GenericMessage [payload=item did not equal result after processing, headers={id=d7bb9dec-36e8-874c-6254-ca9041e82d38, timestamp=1492190593714}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.714 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@67a6a2d0 received message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=d7bb9dec-36e8-874c-6254-ca9041e82d38, timestamp=1492190593714}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.714 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123 received message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=29cbd691-78df-4835-94c0-0684a45fd416, timestamp=1492190593714}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.715 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.715 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123' produced no reply for request Message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=29cbd691-78df-4835-94c0-0684a45fd416, timestamp=1492190593714}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.715 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-process-events', message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=d7bb9dec-36e8-874c-6254-ca9041e82d38, timestamp=1492190593714}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.716 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-process-events', message: GenericMessage [payload=item did not equal result after processing, headers={id=207610ef-a7b4-9496-8403-b27c1912d7df, timestamp=1492190593716}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.716 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@67a6a2d0 received message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=207610ef-a7b4-9496-8403-b27c1912d7df, timestamp=1492190593716}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.716 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123 received message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=e005b2f1-5009-b9d4-b9ff-7c7d7f302b27, timestamp=1492190593716}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.716 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.716 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123' produced no reply for request Message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=e005b2f1-5009-b9d4-b9ff-7c7d7f302b27, timestamp=1492190593716}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.717 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-process-events', message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=207610ef-a7b4-9496-8403-b27c1912d7df, timestamp=1492190593716}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.717 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-process-events', message: GenericMessage [payload=item did not equal result after processing, headers={id=95014d81-4447-fb58-e952-69c71775c3e1, timestamp=1492190593717}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.717 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@67a6a2d0 received message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=95014d81-4447-fb58-e952-69c71775c3e1, timestamp=1492190593717}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.717 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123 received message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=3c249264-05d4-1dad-1220-411d52ce027c, timestamp=1492190593717}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.717 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.717 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@49e0b123' produced no reply for request Message: GenericMessage [payload=byte[42], headers={contentType=application/json, id=3c249264-05d4-1dad-1220-411d52ce027c, timestamp=1492190593717}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.718 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-process-events', message: [Payload String content=item did not equal result after processing][Headers={contentType=application/json, id=95014d81-4447-fb58-e952-69c71775c3e1, timestamp=1492190593717}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.718 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-write-events', message: GenericMessage [payload=3 items to be written., headers={id=c3cce877-7694-30f0-7b92-80e573eb91ca, timestamp=1492190593718}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.718 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@2e85e3f1 received message: [Payload String content=3 items to be written.][Headers={contentType=application/json, id=c3cce877-7694-30f0-7b92-80e573eb91ca, timestamp=1492190593718}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.718 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@195498aa received message: GenericMessage [payload=byte[22], headers={contentType=application/json, id=bd1d1577-8615-edc8-3a35-cda2e5def5e1, timestamp=1492190593718}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.718 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.719 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@195498aa' produced no reply for request Message: GenericMessage [payload=byte[22], headers={contentType=application/json, id=bd1d1577-8615-edc8-3a35-cda2e5def5e1, timestamp=1492190593718}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.719 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-write-events', message: [Payload String content=3 items to be written.][Headers={contentType=application/json, id=c3cce877-7694-30f0-7b92-80e573eb91ca, timestamp=1492190593718}]
14-Apr-2017 17:23:13 >> -4
14-Apr-2017 17:23:13 >> -5
14-Apr-2017 17:23:13 >> -6
14-Apr-2017 17:23:13 2017-04-14 17:23:13.719 DEBUG 16083 --- [           main] s.c.t.b.l.EventEmittingItemWriteListener : Executing afterWrite: [-4, -5, -6]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.719 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'item-write-events', message: GenericMessage [payload=3 items have been written., headers={id=386a920e-1ceb-4a9a-8eed-17a6f5fd8f5c, timestamp=1492190593719}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.719 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@2e85e3f1 received message: [Payload String content=3 items have been written.][Headers={contentType=application/json, id=386a920e-1ceb-4a9a-8eed-17a6f5fd8f5c, timestamp=1492190593719}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.720 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@195498aa received message: GenericMessage [payload=byte[26], headers={contentType=application/json, id=f1338737-a25f-4a65-7f17-d44540ba2fbc, timestamp=1492190593720}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.720 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.720 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@195498aa' produced no reply for request Message: GenericMessage [payload=byte[26], headers={contentType=application/json, id=f1338737-a25f-4a65-7f17-d44540ba2fbc, timestamp=1492190593720}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.720 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'item-write-events', message: [Payload String content=3 items have been written.][Headers={contentType=application/json, id=386a920e-1ceb-4a9a-8eed-17a6f5fd8f5c, timestamp=1492190593719}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.722 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'chunk-events', message: GenericMessage [payload=After Chunk Processing, headers={id=f0248808-852e-684e-2f83-cebd5f846bd5, timestamp=1492190593722}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.723 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@5d5574c7 received message: GenericMessage [payload=After Chunk Processing, headers={id=f0248808-852e-684e-2f83-cebd5f846bd5, timestamp=1492190593722}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.723 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34 received message: GenericMessage [payload=byte[22], headers={contentType=text/plain, id=65cd4f2e-e5ac-a9aa-022a-c1909c9db6ce, timestamp=1492190593723}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.723 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.723 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34' produced no reply for request Message: GenericMessage [payload=byte[22], headers={contentType=text/plain, id=65cd4f2e-e5ac-a9aa-022a-c1909c9db6ce, timestamp=1492190593723}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.723 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'chunk-events', message: GenericMessage [payload=After Chunk Processing, headers={id=f0248808-852e-684e-2f83-cebd5f846bd5, timestamp=1492190593722}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.724 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'chunk-events', message: GenericMessage [payload=Before Chunk Processing, headers={id=5728f792-80fd-a319-b0b9-2656843a37a4, timestamp=1492190593724}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.724 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@5d5574c7 received message: GenericMessage [payload=Before Chunk Processing, headers={id=5728f792-80fd-a319-b0b9-2656843a37a4, timestamp=1492190593724}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.724 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34 received message: GenericMessage [payload=byte[23], headers={contentType=text/plain, id=02298257-aeaf-7bd5-b1cc-e392fe169dcb, timestamp=1492190593724}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.724 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.724 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34' produced no reply for request Message: GenericMessage [payload=byte[23], headers={contentType=text/plain, id=02298257-aeaf-7bd5-b1cc-e392fe169dcb, timestamp=1492190593724}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.725 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'chunk-events', message: GenericMessage [payload=Before Chunk Processing, headers={id=5728f792-80fd-a319-b0b9-2656843a37a4, timestamp=1492190593724}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.727 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'chunk-events', message: GenericMessage [payload=After Chunk Processing, headers={id=fa5fbe15-b567-3f3c-020e-8cadc3c86344, timestamp=1492190593727}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.727 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@5d5574c7 received message: GenericMessage [payload=After Chunk Processing, headers={id=fa5fbe15-b567-3f3c-020e-8cadc3c86344, timestamp=1492190593727}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.727 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34 received message: GenericMessage [payload=byte[22], headers={contentType=text/plain, id=964e84fd-5c38-d48d-2855-ef100c45c360, timestamp=1492190593727}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.727 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.728 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@5a7a6d34' produced no reply for request Message: GenericMessage [payload=byte[22], headers={contentType=text/plain, id=964e84fd-5c38-d48d-2855-ef100c45c360, timestamp=1492190593727}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.728 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'chunk-events', message: GenericMessage [payload=After Chunk Processing, headers={id=fa5fbe15-b567-3f3c-020e-8cadc3c86344, timestamp=1492190593727}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.728 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'step-execution-events', message: GenericMessage [payload=StepExecutionEvent: id=1, version=null, name=step2, status=COMPLETED, exitStatus=COMPLETED, readCount=6, filterCount=0, writeCount=6 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=3, rollbackCount=0, exitDescription=, headers={id=8b0c30d5-bd81-556f-74fe-8d818c8d3606, timestamp=1492190593728}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.729 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@563843f1 received message: GenericMessage [payload={"id":1,"version":null,"jobExecutionId":0,"stepName":"step2","status":"COMPLETED","readCount":6,"writeCount":6,"commitCount":3,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593666,"endTime":null,"lastUpdated":1492190593726,"executionContext":{"dirty":true,"empty":false},"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=1, version=null, name=step2, status=COMPLETED, exitStatus=COMPLETED, readCount=6, filterCount=0, writeCount=6 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=3, rollbackCount=0","skipCount":0}, headers={contentType=application/json;charset=UTF-8, id=dfc6b3b1-843f-7887-1b98-6ec7c4c8eff1, timestamp=1492190593729}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.729 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@38cb1606 received message: GenericMessage [payload=byte[685], headers={id=dbc41f57-be65-cef8-f94e-c8eb49079c72, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593729}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.729 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.729 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[originalContentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.729 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@38cb1606' produced no reply for request Message: GenericMessage [payload=byte[685], headers={id=dbc41f57-be65-cef8-f94e-c8eb49079c72, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593729}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.730 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'step-execution-events', message: GenericMessage [payload={"id":1,"version":null,"jobExecutionId":0,"stepName":"step2","status":"COMPLETED","readCount":6,"writeCount":6,"commitCount":3,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593666,"endTime":null,"lastUpdated":1492190593726,"executionContext":{"dirty":true,"empty":false},"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=1, version=null, name=step2, status=COMPLETED, exitStatus=COMPLETED, readCount=6, filterCount=0, writeCount=6 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=3, rollbackCount=0","skipCount":0}, headers={contentType=application/json;charset=UTF-8, id=dfc6b3b1-843f-7887-1b98-6ec7c4c8eff1, timestamp=1492190593729}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.734 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'job-execution-events', message: GenericMessage [payload=JobExecutionEvent: id=0, version=1, startTime=Fri Apr 14 17:23:13 UTC 2017, endTime=Fri Apr 14 17:23:13 UTC 2017, lastUpdated=Fri Apr 14 17:23:13 UTC 2017, status=COMPLETED, exitStatus=org.springframework.cloud.task.batch.listener.support.ExitStatus@603f4e3e, job=[JobInstanceEvent: id=0, version=null, Job=[job]], jobParameters=[{-server.port=0, -logging.level.org.springframework.integration=DEBUG, -spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup}], headers={id=71e06305-c78a-652b-4c0b-74e744d6f13a, timestamp=1492190593733}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.735 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@1884e671 received message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[{"id":0,"version":null,"jobExecutionId":0,"stepName":"step1","status":"COMPLETED","readCount":0,"writeCount":0,"commitCount":1,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593625,"endTime":1492190593657,"lastUpdated":1492190593658,"executionContext":{"dirty":true,"empty":false},"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=0, version=null, name=step1, status=COMPLETED, exitStatus=COMPLETED, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=1, rollbackCount=0","skipCount":0},{"id":1,"version":null,"jobExecutionId":0,"stepName":"step2","status":"COMPLETED","readCount":6,"writeCount":6,"commitCount":3,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593666,"endTime":1492190593731,"lastUpdated":1492190593731,"executionContext":{"dirty":true,"empty":false},"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=1, version=null, name=step2, status=COMPLETED, exitStatus=COMPLETED, readCount=6, filterCount=0, writeCount=6 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=3, rollbackCount=0","skipCount":0}],"status":"COMPLETED","startTime":1492190593589,"createTime":1492190593567,"endTime":1492190593733,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={contentType=application/json;charset=UTF-8, id=df53c666-9da9-d89a-2450-28045938a569, timestamp=1492190593735}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.735 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@1c7843c3 received message: GenericMessage [payload=byte[2154], headers={id=85ed6985-8996-e6c4-ade7-7d5898c215a8, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593735}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.735 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.736 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[originalContentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.736 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@1c7843c3' produced no reply for request Message: GenericMessage [payload=byte[2154], headers={id=85ed6985-8996-e6c4-ade7-7d5898c215a8, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593735}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.736 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'job-execution-events', message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[{"id":0,"version":null,"jobExecutionId":0,"stepName":"step1","status":"COMPLETED","readCount":0,"writeCount":0,"commitCount":1,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593625,"endTime":1492190593657,"lastUpdated":1492190593658,"executionContext":{"dirty":true,"empty":false},"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=0, version=null, name=step1, status=COMPLETED, exitStatus=COMPLETED, readCount=0, filterCount=0, writeCount=0 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=1, rollbackCount=0","skipCount":0},{"id":1,"version":null,"jobExecutionId":0,"stepName":"step2","status":"COMPLETED","readCount":6,"writeCount":6,"commitCount":3,"rollbackCount":0,"readSkipCount":0,"processSkipCount":0,"writeSkipCount":0,"startTime":1492190593666,"endTime":1492190593731,"lastUpdated":1492190593731,"executionContext":{"dirty":true,"empty":false},"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"terminateOnly":false,"filterCount":0,"failureExceptions":[],"summary":"StepExecutionEvent: id=1, version=null, name=step2, status=COMPLETED, exitStatus=COMPLETED, readCount=6, filterCount=0, writeCount=6 readSkipCount=0, writeSkipCount=0, processSkipCount=0, commitCount=3, rollbackCount=0","skipCount":0}],"status":"COMPLETED","startTime":1492190593589,"createTime":1492190593567,"endTime":1492190593733,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"COMPLETED","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={contentType=application/json;charset=UTF-8, id=df53c666-9da9-d89a-2450-28045938a569, timestamp=1492190593735}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.739  INFO 16083 --- [           main] o.s.b.c.l.support.SimpleJobLauncher      : Job: [SimpleJob: [name=job]] completed with the following parameters: [{-server.port=0, -logging.level.org.springframework.integration=DEBUG, -spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup}] and the following status: [COMPLETED]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.740 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : preSend on channel 'task-events', message: GenericMessage [payload=TaskExecution{executionId=0, parentExecutionId=null, exitCode=0, taskName='batchEvents_0', startTime=Fri Apr 14 17:23:13 UTC 2017, endTime=Fri Apr 14 17:23:13 UTC 2017, exitMessage='null', externalExecutionId='null', errorMessage='null', arguments=[--server.port=0, --spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup, --logging.level.org.springframework.integration=DEBUG]}, headers={id=6b4583e2-ebf4-b1ae-00db-38f74272e368, timestamp=1492190593740}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.740 DEBUG 16083 --- [           main] tractMessageChannelBinder$SendingHandler : org.springframework.cloud.stream.binder.AbstractMessageChannelBinder$SendingHandler@7d284f15 received message: GenericMessage [payload={"executionId":0,"parentExecutionId":null,"exitCode":0,"taskName":"batchEvents_0","startTime":1492190593179,"endTime":1492190593739,"exitMessage":null,"externalExecutionId":null,"errorMessage":null,"arguments":["--server.port=0","--spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup","--logging.level.org.springframework.integration=DEBUG"]}, headers={contentType=application/json;charset=UTF-8, id=7b133bd8-eede-ee68-0561-e871b6b2f2b2, timestamp=1492190593740}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.741 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@3303e89e received message: GenericMessage [payload=byte[360], headers={id=257cc81c-7985-6ac8-be6f-2a787e703ddb, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593741}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.741 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[contentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.741 DEBUG 16083 --- [           main] s.i.m.AbstractHeaderMapper$HeaderMatcher : headerName=[originalContentType] WILL be mapped, matched pattern=*
14-Apr-2017 17:23:13 2017-04-14 17:23:13.741 DEBUG 16083 --- [           main] o.s.i.a.outbound.AmqpOutboundEndpoint    : handler 'org.springframework.integration.amqp.outbound.AmqpOutboundEndpoint@3303e89e' produced no reply for request Message: GenericMessage [payload=byte[360], headers={id=257cc81c-7985-6ac8-be6f-2a787e703ddb, contentType=text/plain, originalContentType=application/json;charset=UTF-8, timestamp=1492190593741}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.741 DEBUG 16083 --- [           main] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'task-events', message: GenericMessage [payload={"executionId":0,"parentExecutionId":null,"exitCode":0,"taskName":"batchEvents_0","startTime":1492190593179,"endTime":1492190593739,"exitMessage":null,"externalExecutionId":null,"errorMessage":null,"arguments":["--server.port=0","--spring.cloud.stream.bindings.output.producer.requiredGroups=testgroup","--logging.level.org.springframework.integration=DEBUG"]}, headers={contentType=application/json;charset=UTF-8, id=7b133bd8-eede-ee68-0561-e871b6b2f2b2, timestamp=1492190593740}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.743 DEBUG 16083 --- [           main] o.s.c.t.r.support.SimpleTaskRepository   : Updating: TaskExecution with executionId=0 with the following {exitCode=0, endTime=Fri Apr 14 17:23:13 UTC 2017, exitMessage='null', errorMessage='null'}
14-Apr-2017 17:23:13 2017-04-14 17:23:13.746  INFO 16083 --- [           main] ationConfigEmbeddedWebApplicationContext : Closing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@7a8c8dcf: startup date [Fri Apr 14 17:23:06 UTC 2017]; root of context hierarchy
14-Apr-2017 17:23:13 2017-04-14 17:23:13.748  INFO 16083 --- [           main] s.c.a.AnnotationConfigApplicationContext : Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@3a9b41a0: startup date [Fri Apr 14 17:23:12 UTC 2017]; parent: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@7a8c8dcf
14-Apr-2017 17:23:13 2017-04-14 17:23:13.750  INFO 16083 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase 2147483647
14-Apr-2017 17:23:13 2017-04-14 17:23:13.751  INFO 16083 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase 2147482647
14-Apr-2017 17:23:13 2017-04-14 17:23:13.751 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Unbinding inputs for :interface org.springframework.cloud.stream.messaging.Sink
14-Apr-2017 17:23:13 2017-04-14 17:23:13.751 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Unbinding :interface org.springframework.cloud.stream.messaging.Sink:input
14-Apr-2017 17:23:13 2017-04-14 17:23:13.757  INFO 16083 --- [           main] o.s.a.r.l.SimpleMessageListenerContainer : Waiting for workers to finish.
14-Apr-2017 17:23:13 2017-04-14 17:23:13.775 DEBUG 16083 --- [nts.testgroup-1] o.s.c.s.b.StreamListenerMessageHandler   : handler 'org.springframework.cloud.stream.binding.StreamListenerMessageHandler@5c0ed5ea' produced no reply for request Message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[],"status":"STARTED","startTime":1492190593589,"createTime":1492190593567,"endTime":null,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"UNKNOWN","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={amqp_receivedDeliveryMode=PERSISTENT, amqp_receivedRoutingKey=job-execution-events, amqp_receivedExchange=job-execution-events, amqp_deliveryTag=1, amqp_consumerQueue=job-execution-events.testgroup, amqp_redelivered=false, id=523fa89d-89bd-855a-76e0-48dc213187a2, amqp_consumerTag=amq.ctag-75g62_Klg26u4_38U6jdSA, contentType=application/json;charset=UTF-8, timestamp=1492190593647}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.775 DEBUG 16083 --- [nts.testgroup-1] .DispatchingStreamListenerMessageHandler : handler 'org.springframework.cloud.stream.binding.DispatchingStreamListenerMessageHandler@64c27b67' produced no reply for request Message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[],"status":"STARTED","startTime":1492190593589,"createTime":1492190593567,"endTime":null,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"UNKNOWN","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={amqp_receivedDeliveryMode=PERSISTENT, amqp_receivedRoutingKey=job-execution-events, amqp_receivedExchange=job-execution-events, amqp_deliveryTag=1, amqp_consumerQueue=job-execution-events.testgroup, amqp_redelivered=false, id=523fa89d-89bd-855a-76e0-48dc213187a2, amqp_consumerTag=amq.ctag-75g62_Klg26u4_38U6jdSA, contentType=application/json;charset=UTF-8, timestamp=1492190593647}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.775 DEBUG 16083 --- [nts.testgroup-1] o.s.integration.channel.DirectChannel    : postSend (sent=true) on channel 'input', message: GenericMessage [payload={"id":0,"version":1,"jobParameters":{"parameters":{"-server.port":{"identifying":false,"value":"0","type":"STRING"},"-logging.level.org.springframework.integration":{"identifying":false,"value":"DEBUG","type":"STRING"},"-spring.cloud.stream.bindings.output.producer.requiredGroups":{"identifying":false,"value":"testgroup","type":"STRING"}},"empty":false},"jobInstance":{"id":0,"version":null,"jobName":"job","instanceId":0},"stepExecutions":[],"status":"STARTED","startTime":1492190593589,"createTime":1492190593567,"endTime":null,"lastUpdated":1492190593589,"exitStatus":{"exitCode":"UNKNOWN","exitDescription":""},"executionContext":{"dirty":false,"empty":true},"failureExceptions":[],"jobConfigurationName":null,"jobId":0,"allFailureExceptions":[]}, headers={amqp_receivedDeliveryMode=PERSISTENT, amqp_receivedRoutingKey=job-execution-events, amqp_receivedExchange=job-execution-events, amqp_deliveryTag=1, amqp_consumerQueue=job-execution-events.testgroup, amqp_redelivered=false, id=523fa89d-89bd-855a-76e0-48dc213187a2, amqp_consumerTag=amq.ctag-75g62_Klg26u4_38U6jdSA, contentType=application/json;charset=UTF-8, timestamp=1492190593647}]
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] o.s.a.r.l.SimpleMessageListenerContainer : Successfully waited for workers to finish.
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] o.s.i.a.i.AmqpInboundChannelAdapter      : stopped inbound.job-execution-events.testgroup
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Unbinding inputs for :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Unbinding inputs for :interface org.springframework.cloud.task.listener.TaskEventAutoConfiguration$TaskEventChannels
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase 0
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] ProxyFactoryBean$MethodInvocationGateway : stopped taskEventListener
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] ProxyFactoryBean$MethodInvocationGateway : stopped taskEventListener
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] ProxyFactoryBean$MethodInvocationGateway : stopped taskEventListener
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] o.s.i.gateway.GatewayProxyFactoryBean    : stopped taskEventListener
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : Removing {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] o.s.i.channel.PublishSubscribeChannel    : Channel 'batchEvents:0.errorChannel' has 0 subscriber(s).
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] o.s.i.endpoint.EventDrivenConsumer       : stopped _org.springframework.integration.errorLogger
14-Apr-2017 17:23:13 2017-04-14 17:23:13.780  INFO 16083 --- [           main] o.s.c.support.DefaultLifecycleProcessor  : Stopping beans in phase -2147482648
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Unbinding outputs for :interface org.springframework.cloud.stream.messaging.Sink
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Unbinding outputs for :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:item-write-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:skip-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:job-execution-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:item-process-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:step-execution-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:chunk-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.batch.listener.BatchEventAutoConfiguration$BatchEventsChannels:item-read-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Unbinding outputs for :interface org.springframework.cloud.task.listener.TaskEventAutoConfiguration$TaskEventChannels
14-Apr-2017 17:23:13 2017-04-14 17:23:13.781 DEBUG 16083 --- [           main] o.s.c.s.binding.BindableProxyFactory     : Binding :interface org.springframework.cloud.task.listener.TaskEventAutoConfiguration$TaskEventChannels:task-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.783  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Unregistering JMX-exposed beans on shutdown
14-Apr-2017 17:23:13 2017-04-14 17:23:13.783  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Unregistering JMX-exposed beans
14-Apr-2017 17:23:13 2017-04-14 17:23:13.783  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: skip-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.783  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: input
14-Apr-2017 17:23:13 2017-04-14 17:23:13.783  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: step-execution-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.783  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: nullChannel
14-Apr-2017 17:23:13 2017-04-14 17:23:13.784  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: errorChannel
14-Apr-2017 17:23:13 2017-04-14 17:23:13.784  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: item-read-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.784  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: item-write-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.784  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: item-process-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.784  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: chunk-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.784  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: task-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.784  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: job-execution-events
14-Apr-2017 17:23:13 2017-04-14 17:23:13.784  INFO 16083 --- [           main] o.s.i.monitor.IntegrationMBeanExporter   : Summary on shutdown: _org.springframework.integration.errorLogger.handler
14-Apr-2017 17:23:13 2017-04-14 17:23:13.785  INFO 16083 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Unregistering JMX-exposed beans on shutdown
14-Apr-2017 17:23:13 2017-04-14 17:23:13.785  INFO 16083 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Unregistering JMX-exposed beans
14-Apr-2017 17:23:13 2017-04-14 17:23:13.801  INFO 16083 --- [           main] o.s.s.c.ThreadPoolTaskScheduler          : Shutting down ExecutorService 'taskScheduler'
14-Apr-2017 17:23:13 2017-04-14 17:23:13.867  INFO 16083 --- [           main] o.a.maven.surefire.booter.ForkedBooter   : Started ForkedBooter in 8.249 seconds (JVM running for 8.894)
14-Apr-2017 17:24:14 Tests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 68.631 sec <<< FAILURE! - in io.spring.cloud.BatchEventsApplicationTests
14-Apr-2017 17:24:14 testExecution(io.spring.cloud.BatchEventsApplicationTests)  Time elapsed: 68.397 sec  <<< FAILURE!
14-Apr-2017 17:24:14 java.lang.AssertionError: The latch did not count down to zero before timeout
14-Apr-2017 17:24:14         at org.junit.Assert.fail(Assert.java:88)
14-Apr-2017 17:24:14         at org.junit.Assert.assertTrue(Assert.java:41)
14-Apr-2017 17:24:14         at io.spring.cloud.BatchEventsApplicationTests.testExecution(BatchEventsApplicationTests.java:48)
14-Apr-2017 17:24:14
14-Apr-2017 17:24:14
14-Apr-2017 17:24:14 Results :
14-Apr-2017 17:24:14
14-Apr-2017 17:24:14 Failed tests:
14-Apr-2017 17:24:14   BatchEventsApplicationTests.testExecution:48 The latch did not count down to zero before timeout
14-Apr-2017 17:24:14
14-Apr-2017 17:24:14 Tests run: 1, Failures: 1, Errors: 0, Skipped: 0
14-Apr-2017 17:24:14
14-Apr-2017 17:24:14 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:24:14 [INFO] Reactor Summary:
14-Apr-2017 17:24:14 [INFO]
14-Apr-2017 17:24:14 [INFO] Spring Cloud Task Dependencies ..................... SUCCESS [  0.211 s]
14-Apr-2017 17:24:14 [INFO] Spring Cloud Task Build ............................ SUCCESS [  1.845 s]
14-Apr-2017 17:24:14 [INFO] Spring Cloud Task Core ............................. SUCCESS [ 19.594 s]
14-Apr-2017 17:24:14 [INFO] Spring Cloud Task Batch ............................ SUCCESS [ 51.771 s]
14-Apr-2017 17:24:14 [INFO] Spring Cloud Task Stream ........................... SUCCESS [ 24.006 s]
14-Apr-2017 17:24:14 [INFO] Spring Cloud Task Starter .......................... SUCCESS [  0.545 s]
14-Apr-2017 17:24:14 [INFO] Timestamp Task ..................................... SUCCESS [  8.168 s]
14-Apr-2017 17:24:14 [INFO] Batch Job Sample Application ....................... SUCCESS [  4.745 s]
14-Apr-2017 17:24:14 [INFO] Task Sink Sample Application ....................... SUCCESS [  8.055 s]
14-Apr-2017 17:24:14 [INFO] Task Processor Sample Application .................. SUCCESS [  7.594 s]
14-Apr-2017 17:24:14 [INFO] Partitioned Batch Job .............................. SUCCESS [  1.497 s]
14-Apr-2017 17:24:14 [INFO] Task Events ........................................ SUCCESS [  1.344 s]
14-Apr-2017 17:24:14 [INFO] Batch Events Sample Application .................... FAILURE [01:09 min]
14-Apr-2017 17:24:14 [INFO] Spring Cloud Task Samples .......................... SKIPPED
14-Apr-2017 17:24:14 [INFO] Spring Cloud Task Integration Tests ................ SKIPPED
14-Apr-2017 17:24:14 [INFO] Spring Cloud Task Docs ............................. SKIPPED
14-Apr-2017 17:24:14 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:24:14 [INFO] BUILD FAILURE
14-Apr-2017 17:24:14 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:24:14 [INFO] Total time: 03:22 min
14-Apr-2017 17:24:14 [INFO] Finished at: 2017-04-14T17:24:14+00:00
14-Apr-2017 17:24:14 [INFO] Final Memory: 110M/761M
14-Apr-2017 17:24:14 [INFO] ------------------------------------------------------------------------
14-Apr-2017 17:24:14 [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.18.1:test (default-test) on project batch-events: There are test failures.
14-Apr-2017 17:24:14 [ERROR]
14-Apr-2017 17:24:14 [ERROR] Please refer to /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1/spring-cloud-task-samples/batch-events/target/surefire-reports for the individual test results.
14-Apr-2017 17:24:14 [ERROR] -> [Help 1]
14-Apr-2017 17:24:14 [ERROR]
14-Apr-2017 17:24:14 [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
14-Apr-2017 17:24:14 [ERROR] Re-run Maven using the -X switch to enable full debug logging.
14-Apr-2017 17:24:14 [ERROR]
14-Apr-2017 17:24:14 [ERROR] For more information about the errors and possible solutions, please read the following articles:
14-Apr-2017 17:24:14 [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
14-Apr-2017 17:24:14 [ERROR]
14-Apr-2017 17:24:14 [ERROR] After correcting the problems, you can resume the build with the command
14-Apr-2017 17:24:14 [ERROR]   mvn <goals> -rf :batch-events
14-Apr-2017 17:24:14
14-Apr-2017 17:24:14 Failing task since return code of [/opt/jdk-8/bin/java -cp /opt/maven-3.3/boot/plexus-classworlds-2.5.2.jar -Dclassworlds.conf=/tmp/artifactoryM23020727961863542412conf -DbuildInfoConfig.propertiesFile=/tmp/buildInfo3667964813008810341properties -Xmx2048m -XX:MaxPermSize=2048m -Dmaven.home=/opt/maven-3.3 -Dmaven.multiModuleProjectDirectory=/opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1 org.codehaus.plexus.classworlds.launcher.Launcher clean install -Pfull,skipIntegrationTests] was 1 while expected 0
14-Apr-2017 17:24:14 Parsing test results under /opt/bamboo-home/xml-data/build-dir/SCT-STASK-JOB1...
14-Apr-2017 17:24:14 Finished task 'Build and publish Spring Cloud Task Modules' with result: Failed
14-Apr-2017 17:24:14 Running post build plugin 'Docker Container Cleanup'
14-Apr-2017 17:24:14 Running post build plugin 'NCover Results Collector'
14-Apr-2017 17:24:14 Running post build plugin 'Artifactory Release Management Staging'
14-Apr-2017 17:24:14 Running post build plugin 'Artifactory Build Info Copier'
14-Apr-2017 17:24:14 Running post build plugin 'Artifactory Gradle Properties Info Copier'
14-Apr-2017 17:24:14 Running post build plugin 'Post Command Agent Runner'
14-Apr-2017 17:24:14 running post command on agent
14-Apr-2017 17:24:14 build failed, running fail command
14-Apr-2017 17:24:14 command is:
14-Apr-2017 17:24:14 Running post build plugin 'Clover Results Collector'
14-Apr-2017 17:24:14 Running post build plugin 'npm Cache Cleanup'
14-Apr-2017 17:24:14 Running post build plugin 'Artifact Copier'
14-Apr-2017 17:24:14 Publishing an artifact: Test Results
14-Apr-2017 17:24:14 Finished publishing of artifact Shared artifact: [Test Results], pattern: [**/target/surefire-reports/**/*.*] in 146.5 ms
14-Apr-2017 17:24:14 Finalising the build...
14-Apr-2017 17:24:14 Stopping timer.
14-Apr-2017 17:24:14 Build SCT-STASK-JOB1-311 completed.
14-Apr-2017 17:24:14 Running on server: post build plugin 'Post Command Server Runner'
14-Apr-2017 17:24:14 Running on server: post build plugin 'NCover Results Collector'
14-Apr-2017 17:24:14 Running on server: post build plugin 'Build Hanging Detection Configuration'
14-Apr-2017 17:24:14 Running on server: post build plugin 'Clover Delta Calculator'
14-Apr-2017 17:24:14 Running on server: post build plugin 'Maven Dependencies Postprocessor'
14-Apr-2017 17:24:14 All post build plugins have finished
14-Apr-2017 17:24:14 Generating build results summary...
14-Apr-2017 17:24:15 Saving build results to disk...
14-Apr-2017 17:24:15 Logging substituted variables...
14-Apr-2017 17:24:15 Indexing build results...
14-Apr-2017 17:24:15 Finished building SCT-STASK-JOB1-311.