Class cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest

2

tests

0

failures

0

ignored

12.465s

duration

100%

successful

Tests

Test Duration Result
testLocalErrorReportingInMapper 5.288s passed
testLocalErrorReportingInReducer 7.177s passed

Standard output

2016-05-18 03:18:46,581 INFO  platform.PlatformRunner (PlatformRunner.java:getPlatformClassesFromClasspath(197)) - found 1 test platforms from classpath
2016-05-18 03:18:46,612 INFO  platform.PlatformRunner (PlatformRunner.java:addPlatform(246)) - adding test: cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest, with platform: hadoop2-mr1
2016-05-18 03:18:47,005 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 03:18:47,007 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-18 03:18:47,120 INFO  property.AppProps (AppProps.java:getAppID(177)) - using app.id: D6F09026E8BB4F96A8992EF63D6FC982
2016-05-18 03:18:47,432 WARN  util.NativeCodeLoader (NativeCodeLoader.java:<clinit>(62)) - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
2016-05-18 03:18:47,763 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.116
2016-05-18 03:18:47,767 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-18 03:18:47,768 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 03:18:47,917 INFO  util.Version (Version.java:printBanner(82)) - Concurrent, Inc - Cascading 3.1.0
2016-05-18 03:18:47,917 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [reducer fail test] starting
2016-05-18 03:18:47,922 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [reducer fail test]  parallel execution of flows is enabled: false
2016-05-18 03:18:47,923 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [reducer fail test]  executing total flows: 1
2016-05-18 03:18:47,923 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [reducer fail test]  allocating management threads: 1
2016-05-18 03:18:47,925 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [reducer fail test] starting flow: reducer fail test
2016-05-18 03:18:47,925 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] at least one sink is marked for delete
2016-05-18 03:18:47,930 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 03:18:47,995 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] starting
2016-05-18 03:18:47,996 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test]  source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/classes/test/data/apache.10.txt"]
2016-05-18 03:18:47,996 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test]  sink: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/output/hadoop2-mr1/cascadingstatslocalhadooperrorplatform/testLocalErrorReportingInReducer/reducerfail"]
2016-05-18 03:18:47,996 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test]  parallel execution of steps is enabled: false
2016-05-18 03:18:47,997 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test]  executing total steps: 1
2016-05-18 03:18:47,997 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test]  allocating management threads: 1
2016-05-18 03:18:47,998 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] starting step: (1/1) ...tingInReducer/reducerfail
2016-05-18 03:18:48,540 WARN  conf.Configuration (Configuration.java:loadProperty(2358)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/tmp/mapred/staging/teamcity693731629/.staging/job_local693731629_0001/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-18 03:18:48,552 WARN  conf.Configuration (Configuration.java:loadProperty(2358)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/tmp/mapred/staging/teamcity693731629/.staging/job_local693731629_0001/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-18 03:18:48,774 WARN  conf.Configuration (Configuration.java:loadProperty(2358)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local693731629_0001/job_local693731629_0001.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-18 03:18:48,787 WARN  conf.Configuration (Configuration.java:loadProperty(2358)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local693731629_0001/job_local693731629_0001.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-18 03:18:48,796 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] submitted hadoop job: job_local693731629_0001
2016-05-18 03:18:48,797 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] tracking url: http://localhost:8080/
2016-05-18 03:18:48,908 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/classes/test/data/apache.10.txt
2016-05-18 03:18:49,054 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-18 03:18:49,055 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-18 03:18:49,070 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B9CC17652B9146A2AFC548963C2DEAC4, ordinal: 0
2016-05-18 03:18:49,109 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/classes/test/data/apache.10.txt"]
2016-05-18 03:18:49,109 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(failing reducer)[by:[{1}:'ip']]
2016-05-18 03:18:49,111 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B9CC17652B9146A2AFC548963C2DEAC4, mem on start (mb), free: 146, total: 281, max: 672
2016-05-18 03:18:49,120 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B9CC17652B9146A2AFC548963C2DEAC4, mem on close (mb), free: 146, total: 281, max: 672
2016-05-18 03:18:49,236 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-18 03:18:49,236 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-18 03:18:49,250 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: E45B51A5BF6D41AF8ED7D69A0191A683, ordinal: 1
2016-05-18 03:18:49,253 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(failing reducer)[by:[{1}:'ip']]
2016-05-18 03:18:49,253 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/output/hadoop2-mr1/cascadingstatslocalhadooperrorplatform/testLocalErrorReportingInReducer/reducerfail"]
2016-05-18 03:18:49,254 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: E45B51A5BF6D41AF8ED7D69A0191A683, mem on start (mb), free: 135, total: 281, max: 672
2016-05-18 03:18:49,273 ERROR element.TrapHandler (TrapHandler.java:handleReThrowableException(115)) - caught Throwable, no trap available, rethrowing
cascading.pipe.OperatorException: [failing reducer][cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest.testLocalErrorReportingInReducer(CascadingStatsLocalHadoopErrorPlatformTest.java:124)] operator Every failed executing operation: TestFailAggregator[decl:'count']
	at cascading.flow.stream.element.AggregatorEveryStage.receive(AggregatorEveryStage.java:138)
	at cascading.flow.stream.element.AggregatorEveryStage.receive(AggregatorEveryStage.java:41)
	at cascading.flow.stream.duct.OpenReducingDuct.receive(OpenReducingDuct.java:49)
	at cascading.flow.stream.duct.OpenReducingDuct.receive(OpenReducingDuct.java:28)
	at cascading.flow.hadoop.stream.HadoopGroupGate.accept(HadoopGroupGate.java:147)
	at cascading.flow.hadoop.FlowReducer.reduce(FlowReducer.java:146)
	at org.apache.hadoop.mapred.ReduceTask.runOldReducer(ReduceTask.java:444)
	at org.apache.hadoop.mapred.ReduceTask.run(ReduceTask.java:392)
	at org.apache.hadoop.mapred.LocalJobRunner$Job$ReduceTaskRunnable.run(LocalJobRunner.java:319)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.RuntimeException: failed
	at cascading.TestFailAggregator.aggregate(TestFailAggregator.java:54)
	at cascading.flow.stream.element.AggregatorEveryStage.receive(AggregatorEveryStage.java:130)
	... 13 more
2016-05-18 03:18:49,275 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: E45B51A5BF6D41AF8ED7D69A0191A683, mem on close (mb), free: 134, total: 281, max: 672
2016-05-18 03:18:49,278 WARN  mapred.LocalJobRunner (LocalJobRunner.java:run(560)) - job_local693731629_0001
java.lang.Exception: cascading.pipe.OperatorException: [failing reducer][cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest.testLocalErrorReportingInReducer(CascadingStatsLocalHadoopErrorPlatformTest.java:124)] operator Every failed executing operation: TestFailAggregator[decl:'count']
	at org.apache.hadoop.mapred.LocalJobRunner$Job.runTasks(LocalJobRunner.java:462)
	at org.apache.hadoop.mapred.LocalJobRunner$Job.run(LocalJobRunner.java:529)
Caused by: cascading.pipe.OperatorException: [failing reducer][cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest.testLocalErrorReportingInReducer(CascadingStatsLocalHadoopErrorPlatformTest.java:124)] operator Every failed executing operation: TestFailAggregator[decl:'count']
	at cascading.flow.stream.element.AggregatorEveryStage.receive(AggregatorEveryStage.java:138)
	at cascading.flow.stream.element.AggregatorEveryStage.receive(AggregatorEveryStage.java:41)
	at cascading.flow.stream.duct.OpenReducingDuct.receive(OpenReducingDuct.java:49)
	at cascading.flow.stream.duct.OpenReducingDuct.receive(OpenReducingDuct.java:28)
	at cascading.flow.hadoop.stream.HadoopGroupGate.accept(HadoopGroupGate.java:147)
	at cascading.flow.hadoop.FlowReducer.reduce(FlowReducer.java:146)
	at org.apache.hadoop.mapred.ReduceTask.runOldReducer(ReduceTask.java:444)
	at org.apache.hadoop.mapred.ReduceTask.run(ReduceTask.java:392)
	at org.apache.hadoop.mapred.LocalJobRunner$Job$ReduceTaskRunnable.run(LocalJobRunner.java:319)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.RuntimeException: failed
	at cascading.TestFailAggregator.aggregate(TestFailAggregator.java:54)
	at cascading.flow.stream.element.AggregatorEveryStage.receive(AggregatorEveryStage.java:130)
	... 13 more
2016-05-18 03:18:53,798 WARN  flow.Flow (BaseFlow.java:logWarn(1516)) - [reducer fail test] hadoop job job_local693731629_0001 state at FAILED
2016-05-18 03:18:53,799 WARN  flow.Flow (BaseFlow.java:logWarn(1516)) - [reducer fail test] failure info: NA
2016-05-18 03:18:53,799 WARN  flow.Flow (BaseFlow.java:logWarn(1516)) - [reducer fail test] task completion events identify failed tasks
2016-05-18 03:18:53,800 WARN  flow.Flow (BaseFlow.java:logWarn(1516)) - [reducer fail test] task completion events count: 0
2016-05-18 03:18:53,812 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] stopping all jobs
2016-05-18 03:18:53,812 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] stopping: (1/1) ...tingInReducer/reducerfail
2016-05-18 03:18:53,813 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] stopped all jobs
2016-05-18 03:18:53,814 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/output/hadoop2-mr1/cascadingstatslocalhadooperrorplatform/testLocalErrorReportingInReducer/reducerfail/_temporary
2016-05-18 03:18:53,815 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test]  completed in: 00:05.818
2016-05-18 03:18:53,815 WARN  cascade.Cascade (BaseCascade.java:logWarn(878)) - [reducer fail test] flow failed: reducer fail test
cascading.flow.FlowException: local step failed: (1/1) ...tingInReducer/reducerfail
	at cascading.flow.planner.FlowStepJob.blockOnJob(FlowStepJob.java:293)
	at cascading.flow.planner.FlowStepJob.start(FlowStepJob.java:184)
	at cascading.flow.planner.FlowStepJob.call(FlowStepJob.java:146)
	at cascading.flow.planner.FlowStepJob.call(FlowStepJob.java:48)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
Caused by: cascading.pipe.OperatorException: [failing reducer][cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest.testLocalErrorReportingInReducer(CascadingStatsLocalHadoopErrorPlatformTest.java:124)] operator Every failed executing operation: TestFailAggregator[decl:'count']
	at cascading.flow.stream.element.AggregatorEveryStage.receive(AggregatorEveryStage.java:138)
	at cascading.flow.stream.element.AggregatorEveryStage.receive(AggregatorEveryStage.java:41)
	at cascading.flow.stream.duct.OpenReducingDuct.receive(OpenReducingDuct.java:49)
	at cascading.flow.stream.duct.OpenReducingDuct.receive(OpenReducingDuct.java:28)
	at cascading.flow.hadoop.stream.HadoopGroupGate.accept(HadoopGroupGate.java:147)
	at cascading.flow.hadoop.FlowReducer.reduce(FlowReducer.java:146)
	at org.apache.hadoop.mapred.ReduceTask.runOldReducer(ReduceTask.java:444)
	at org.apache.hadoop.mapred.ReduceTask.run(ReduceTask.java:392)
	at org.apache.hadoop.mapred.LocalJobRunner$Job$ReduceTaskRunnable.run(LocalJobRunner.java:319)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	... 4 more
Caused by: java.lang.RuntimeException: failed
	at cascading.TestFailAggregator.aggregate(TestFailAggregator.java:54)
	at cascading.flow.stream.element.AggregatorEveryStage.receive(AggregatorEveryStage.java:130)
	... 13 more
2016-05-18 03:18:53,816 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [reducer fail test] stopping all flows
2016-05-18 03:18:53,816 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [reducer fail test] stopping flow: reducer fail test
2016-05-18 03:18:53,816 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] stopping all jobs
2016-05-18 03:18:53,817 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] stopping: (1/1) ...tingInReducer/reducerfail
2016-05-18 03:18:53,817 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [reducer fail test] stopped all jobs
2016-05-18 03:18:53,817 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [reducer fail test] stopped all flows
2016-05-18 03:18:53,838 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 03:18:53,839 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-18 03:18:53,895 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.051
2016-05-18 03:18:53,896 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 03:18:53,897 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 03:18:53,915 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [mapper fail test] starting
2016-05-18 03:18:53,916 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [mapper fail test]  parallel execution of flows is enabled: false
2016-05-18 03:18:53,916 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [mapper fail test]  executing total flows: 1
2016-05-18 03:18:53,917 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [mapper fail test]  allocating management threads: 1
2016-05-18 03:18:53,917 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [mapper fail test] starting flow: mapper fail test
2016-05-18 03:18:53,917 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] at least one sink is marked for delete
2016-05-18 03:18:53,917 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 03:18:53,920 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] starting
2016-05-18 03:18:53,921 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test]  source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/classes/test/data/apache.10.txt"]
2016-05-18 03:18:53,921 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test]  sink: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/output/hadoop2-mr1/cascadingstatslocalhadooperrorplatform/testLocalErrorReportingInMapper/mapperfail"]
2016-05-18 03:18:53,921 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test]  parallel execution of steps is enabled: false
2016-05-18 03:18:53,921 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test]  executing total steps: 1
2016-05-18 03:18:53,921 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test]  allocating management threads: 1
2016-05-18 03:18:53,922 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] starting step: (1/1) ...ortingInMapper/mapperfail
2016-05-18 03:18:54,010 WARN  conf.Configuration (Configuration.java:loadProperty(2358)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/tmp/mapred/staging/teamcity122028440/.staging/job_local122028440_0002/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-18 03:18:54,012 WARN  conf.Configuration (Configuration.java:loadProperty(2358)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/tmp/mapred/staging/teamcity122028440/.staging/job_local122028440_0002/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-18 03:18:54,094 WARN  conf.Configuration (Configuration.java:loadProperty(2358)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local122028440_0002/job_local122028440_0002.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-18 03:18:54,098 WARN  conf.Configuration (Configuration.java:loadProperty(2358)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local122028440_0002/job_local122028440_0002.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-18 03:18:54,099 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] submitted hadoop job: job_local122028440_0002
2016-05-18 03:18:54,100 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] tracking url: http://localhost:8080/
2016-05-18 03:18:54,110 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/classes/test/data/apache.10.txt
2016-05-18 03:18:54,120 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-18 03:18:54,120 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-18 03:18:54,129 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: A129390CAC7B4C248999CE5FEC90F498, ordinal: 0
2016-05-18 03:18:54,130 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/classes/test/data/apache.10.txt"]
2016-05-18 03:18:54,131 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/output/hadoop2-mr1/cascadingstatslocalhadooperrorplatform/testLocalErrorReportingInMapper/mapperfail"]
2016-05-18 03:18:54,131 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A129390CAC7B4C248999CE5FEC90F498, mem on start (mb), free: 92, total: 281, max: 672
2016-05-18 03:18:54,132 ERROR element.TrapHandler (TrapHandler.java:handleReThrowableException(115)) - caught Throwable, no trap available, rethrowing
cascading.CascadingException: testing
	at cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest$FailFunction.operate(CascadingStatsLocalHadoopErrorPlatformTest.java:81)
	at cascading.flow.stream.element.FunctionEachStage.receive(FunctionEachStage.java:100)
	at cascading.flow.stream.element.FunctionEachStage.receive(FunctionEachStage.java:40)
	at cascading.flow.stream.element.SourceStage.map(SourceStage.java:110)
	at cascading.flow.stream.element.SourceStage.run(SourceStage.java:66)
	at cascading.flow.hadoop.FlowMapper.run(FlowMapper.java:142)
	at org.apache.hadoop.mapred.MapTask.runOldMapper(MapTask.java:430)
	at org.apache.hadoop.mapred.MapTask.run(MapTask.java:342)
	at org.apache.hadoop.mapred.LocalJobRunner$Job$MapTaskRunnable.run(LocalJobRunner.java:243)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
2016-05-18 03:18:54,135 ERROR element.SourceStage (SourceStage.java:map(118)) - caught throwable
cascading.CascadingException: testing
	at cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest$FailFunction.operate(CascadingStatsLocalHadoopErrorPlatformTest.java:81)
	at cascading.flow.stream.element.FunctionEachStage.receive(FunctionEachStage.java:100)
	at cascading.flow.stream.element.FunctionEachStage.receive(FunctionEachStage.java:40)
	at cascading.flow.stream.element.SourceStage.map(SourceStage.java:110)
	at cascading.flow.stream.element.SourceStage.run(SourceStage.java:66)
	at cascading.flow.hadoop.FlowMapper.run(FlowMapper.java:142)
	at org.apache.hadoop.mapred.MapTask.runOldMapper(MapTask.java:430)
	at org.apache.hadoop.mapred.MapTask.run(MapTask.java:342)
	at org.apache.hadoop.mapred.LocalJobRunner$Job$MapTaskRunnable.run(LocalJobRunner.java:243)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
2016-05-18 03:18:54,138 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A129390CAC7B4C248999CE5FEC90F498, mem on close (mb), free: 92, total: 281, max: 672
2016-05-18 03:18:54,140 WARN  mapred.LocalJobRunner (LocalJobRunner.java:run(560)) - job_local122028440_0002
java.lang.Exception: cascading.CascadingException: testing
	at org.apache.hadoop.mapred.LocalJobRunner$Job.runTasks(LocalJobRunner.java:462)
	at org.apache.hadoop.mapred.LocalJobRunner$Job.run(LocalJobRunner.java:522)
Caused by: cascading.CascadingException: testing
	at cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest$FailFunction.operate(CascadingStatsLocalHadoopErrorPlatformTest.java:81)
	at cascading.flow.stream.element.FunctionEachStage.receive(FunctionEachStage.java:100)
	at cascading.flow.stream.element.FunctionEachStage.receive(FunctionEachStage.java:40)
	at cascading.flow.stream.element.SourceStage.map(SourceStage.java:110)
	at cascading.flow.stream.element.SourceStage.run(SourceStage.java:66)
	at cascading.flow.hadoop.FlowMapper.run(FlowMapper.java:142)
	at org.apache.hadoop.mapred.MapTask.runOldMapper(MapTask.java:430)
	at org.apache.hadoop.mapred.MapTask.run(MapTask.java:342)
	at org.apache.hadoop.mapred.LocalJobRunner$Job$MapTaskRunnable.run(LocalJobRunner.java:243)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
2016-05-18 03:18:59,102 WARN  flow.Flow (BaseFlow.java:logWarn(1516)) - [mapper fail test] hadoop job job_local122028440_0002 state at FAILED
2016-05-18 03:18:59,102 WARN  flow.Flow (BaseFlow.java:logWarn(1516)) - [mapper fail test] failure info: NA
2016-05-18 03:18:59,103 WARN  flow.Flow (BaseFlow.java:logWarn(1516)) - [mapper fail test] task completion events identify failed tasks
2016-05-18 03:18:59,103 WARN  flow.Flow (BaseFlow.java:logWarn(1516)) - [mapper fail test] task completion events count: 0
2016-05-18 03:18:59,103 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] stopping all jobs
2016-05-18 03:18:59,104 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] stopping: (1/1) ...ortingInMapper/mapperfail
2016-05-18 03:18:59,104 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] stopped all jobs
2016-05-18 03:18:59,105 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.4.x/build/test/output/hadoop2-mr1/cascadingstatslocalhadooperrorplatform/testLocalErrorReportingInMapper/mapperfail/_temporary
2016-05-18 03:18:59,106 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test]  completed in: 00:05.185
2016-05-18 03:18:59,106 WARN  cascade.Cascade (BaseCascade.java:logWarn(878)) - [mapper fail test] flow failed: mapper fail test
cascading.flow.FlowException: local step failed: (1/1) ...ortingInMapper/mapperfail
	at cascading.flow.planner.FlowStepJob.blockOnJob(FlowStepJob.java:293)
	at cascading.flow.planner.FlowStepJob.start(FlowStepJob.java:184)
	at cascading.flow.planner.FlowStepJob.call(FlowStepJob.java:146)
	at cascading.flow.planner.FlowStepJob.call(FlowStepJob.java:48)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
Caused by: cascading.CascadingException: testing
	at cascading.stats.hadoop.CascadingStatsLocalHadoopErrorPlatformTest$FailFunction.operate(CascadingStatsLocalHadoopErrorPlatformTest.java:81)
	at cascading.flow.stream.element.FunctionEachStage.receive(FunctionEachStage.java:100)
	at cascading.flow.stream.element.FunctionEachStage.receive(FunctionEachStage.java:40)
	at cascading.flow.stream.element.SourceStage.map(SourceStage.java:110)
	at cascading.flow.stream.element.SourceStage.run(SourceStage.java:66)
	at cascading.flow.hadoop.FlowMapper.run(FlowMapper.java:142)
	at org.apache.hadoop.mapred.MapTask.runOldMapper(MapTask.java:430)
	at org.apache.hadoop.mapred.MapTask.run(MapTask.java:342)
	at org.apache.hadoop.mapred.LocalJobRunner$Job$MapTaskRunnable.run(LocalJobRunner.java:243)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
	... 4 more
2016-05-18 03:18:59,107 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [mapper fail test] stopping all flows
2016-05-18 03:18:59,107 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [mapper fail test] stopping flow: mapper fail test
2016-05-18 03:18:59,107 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] stopping all jobs
2016-05-18 03:18:59,107 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] stopping: (1/1) ...ortingInMapper/mapperfail
2016-05-18 03:18:59,107 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [mapper fail test] stopped all jobs
2016-05-18 03:18:59,108 INFO  cascade.Cascade (BaseCascade.java:logInfo(860)) - [mapper fail test] stopped all flows