07:10:27,314 INFO Test worker context.TestContextManager - @TestExecutionListeners is not present for class [class org.springframework.data.hadoop.mapreduce.JobKillTests]: using defaults.
07:10:27,315 INFO Test worker xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [org/springframework/data/hadoop/mapreduce/JobKillTests-context.xml]
07:10:27,329 INFO Test worker xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [org/springframework/data/hadoop/batch-common.xml]
07:10:27,335 INFO Test worker xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [org/springframework/data/hadoop/hadoop-ctx.xml]
07:10:27,350 INFO Test worker support.DefaultListableBeanFactory - Overriding bean definition for bean 'mainJob': replacing [Generic bean: class [org.springframework.batch.core.configuration.xml.SimpleFlowFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Generic bean: class [org.springframework.batch.core.configuration.xml.JobParserJobFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null]
07:10:27,351 INFO Test worker support.DefaultListableBeanFactory - Overriding bean definition for bean 'taskExecutor': replacing [Generic bean: class [org.springframework.core.task.SyncTaskExecutor]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [org/springframework/data/hadoop/batch-common.xml]] with [Generic bean: class [org.springframework.core.task.SimpleAsyncTaskExecutor]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [org/springframework/data/hadoop/mapreduce/JobKillTests-context.xml]]
07:10:27,351 INFO Test worker support.GenericApplicationContext - Refreshing org.springframework.context.support.GenericApplicationContext@5302f26d: startup date [Thu Sep 05 07:10:27 PDT 2013]; root of context hierarchy
07:10:27,357 INFO Test worker config.PropertyPlaceholderConfigurer - Loading properties file from class path resource [test.properties]
07:10:27,362 INFO Test worker support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@7038ce7b: defining beans [jobRepository,transactionManager,jobLauncher,taskExecutor,ppc,hadoopFs,hadoopResourceLoader,hadoopConfiguration,cfg-init,fs-init,rl-init,org.springframework.data.hadoop.scripting.HdfsScriptRunner#0,org.springframework.batch.core.scope.StepScope#0,script,nop-script,org.springframework.beans.factory.config.CustomEditorConfigurer,org.springframework.batch.core.configuration.xml.CoreNamespacePostProcessor,do-hadoop,mainJob,killer-runner,victim-job,hdp-tasklet,tasklet-victim-job,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor]; root of factory hierarchy
07:10:27,585 INFO SimpleAsyncTaskExecutor-1 mapreduce.JobRunner - Starting job [victim-job]
07:10:27,601 WARN SimpleAsyncTaskExecutor-1 mapred.JobClient - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
07:10:27,603 INFO SimpleAsyncTaskExecutor-1 input.FileInputFormat - Total input paths to process : 5
07:10:28,592 INFO SimpleAsyncTaskExecutor-2 mapreduce.JobRunner - Killing job [victim-job]
07:10:33,601 INFO SimpleAsyncTaskExecutor-3 support.SimpleJobLauncher - Job: [FlowJob: [name=mainJob]] launched with the following parameters: [{}]
07:10:33,614 INFO SimpleAsyncTaskExecutor-3 job.SimpleStepHandler - Executing step: [do-hadoop]
07:10:33,622 INFO SimpleAsyncTaskExecutor-4 mapreduce.JobTasklet - Starting job [tasklet-victim-job]
07:10:33,648 WARN SimpleAsyncTaskExecutor-4 mapred.JobClient - No job jar file set. User classes may not be found. See JobConf(Class) or JobConf#setJar(String).
07:10:33,652 INFO SimpleAsyncTaskExecutor-4 input.FileInputFormat - Total input paths to process : 5
07:10:33,740 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Running job: job_201308191125_0569
07:10:34,741 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - map 0% reduce 0%
07:10:39,120 INFO SimpleAsyncTaskExecutor-3 mapreduce.JobTasklet - Cancelling job tasklet
07:10:39,123 INFO SimpleAsyncTaskExecutor-5 mapreduce.JobTasklet - Killing job [tasklet-victim-job]
07:10:39,146 INFO SimpleAsyncTaskExecutor-3 support.SimpleJobRepository - Parent JobExecution is stopped, so passing message on to StepExecution
07:10:39,146 INFO SimpleAsyncTaskExecutor-3 step.ThreadStepInterruptionPolicy - Step interrupted through StepExecution
07:10:39,147 INFO SimpleAsyncTaskExecutor-3 step.AbstractStep - Encountered interruption executing step: Job interrupted status detected.
07:10:39,149 INFO SimpleAsyncTaskExecutor-3 support.SimpleJobRepository - Parent JobExecution is stopped, so passing message on to StepExecution
07:10:39,159 INFO SimpleAsyncTaskExecutor-3 job.AbstractJob - Encountered interruption executing job: Job interrupted by step execution
07:10:39,166 INFO SimpleAsyncTaskExecutor-3 support.SimpleJobLauncher - Job: [FlowJob: [name=mainJob]] completed with the following parameters: [{}] and the following status: [STOPPED]
07:10:39,748 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - map 20% reduce 0%
07:10:40,749 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - map 40% reduce 0%
07:10:40,750 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Job complete: job_201308191125_0569
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Counters: 23
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Job Counters
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Launched reduce tasks=1
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - SLOTS_MILLIS_MAPS=9117
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Total time spent by all reduces waiting after reserving slots (ms)=0
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Total time spent by all maps waiting after reserving slots (ms)=0
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Launched map tasks=3
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Data-local map tasks=3
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - SLOTS_MILLIS_REDUCES=1819
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - FileSystemCounters
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - FILE_BYTES_READ=17021724
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - HDFS_BYTES_READ=8912833
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - FILE_BYTES_WRITTEN=34152122
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - File Input Format Counters
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Bytes Read=8912593
07:10:40,752 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Map-Reduce Framework
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Map output materialized bytes=17021694
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Combine output records=0
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Map input records=192905
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Physical memory (bytes) snapshot=501415936
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Spilled Records=2945200
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Map output bytes=14076482
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - CPU time spent (ms)=6190
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Total committed heap usage (bytes)=388497408
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Virtual memory (bytes) snapshot=1702805504
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Combine input records=0
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - Map output records=1472600
07:10:40,753 INFO SimpleAsyncTaskExecutor-4 mapred.JobClient - SPLIT_RAW_BYTES=240
07:10:40,754 INFO SimpleAsyncTaskExecutor-4 mapreduce.JobTasklet - Completed job [tasklet-victim-job]
07:10:40,769 INFO SimpleAsyncTaskExecutor-4 mapreduce.JobTasklet - Job [tasklet-victim-job] killed by shutdown