2016-05-18 00:59:38,330 INFO platform.PlatformRunner (PlatformRunner.java:getPlatformClassesFromClasspath(197)) - found 1 test platforms from classpath
2016-05-18 00:59:38,350 INFO platform.PlatformRunner (PlatformRunner.java:addPlatform(246)) - adding test: cascading.tap.TapPlatformTest, with platform: hadoop2-mr1
2016-05-18 00:59:38,373 INFO hadoop2.Hadoop2MR1Platform (Hadoop2MR1Platform.java:setUp(136)) - using cluster
2016-05-18 00:59:39,080 WARN util.NativeCodeLoader (NativeCodeLoader.java:<clinit>(62)) - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
Formatting using clusterid: testClusterID
2016-05-18 00:59:39,534 WARN impl.MetricsConfig (MetricsConfig.java:loadFirst(124)) - Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
2016-05-18 00:59:40,050 WARN server.AuthenticationFilter (AuthenticationFilter.java:init(158)) - 'signature.secret' configuration not set, using a random value as secret
2016-05-18 00:59:42,871 WARN containermanager.AuxServices (AuxServices.java:serviceInit(114)) - The Auxilurary Service named 'mapreduce_shuffle' in the configuration is for class class org.apache.hadoop.mapred.ShuffleHandler which has a name of 'httpshuffle'. Because these are not the same tools trying to send ServiceData and read Service Meta Data may have issues unless the refer to the name in the config.
2016-05-18 00:59:43,012 WARN containermanager.AuxServices (AuxServices.java:serviceInit(114)) - The Auxilurary Service named 'mapreduce_shuffle' in the configuration is for class class org.apache.hadoop.mapred.ShuffleHandler which has a name of 'httpshuffle'. Because these are not the same tools trying to send ServiceData and read Service Meta Data may have issues unless the refer to the name in the config.
2016-05-18 00:59:43,122 WARN containermanager.AuxServices (AuxServices.java:serviceInit(114)) - The Auxilurary Service named 'mapreduce_shuffle' in the configuration is for class class org.apache.hadoop.mapred.ShuffleHandler which has a name of 'httpshuffle'. Because these are not the same tools trying to send ServiceData and read Service Meta Data may have issues unless the refer to the name in the config.
2016-05-18 00:59:43,233 WARN containermanager.AuxServices (AuxServices.java:serviceInit(114)) - The Auxilurary Service named 'mapreduce_shuffle' in the configuration is for class class org.apache.hadoop.mapred.ShuffleHandler which has a name of 'httpshuffle'. Because these are not the same tools trying to send ServiceData and read Service Meta Data may have issues unless the refer to the name in the config.
2016-05-18 01:00:00,303 INFO platform.TestPlatform (TestPlatform.java:getGlobalProperties(75)) - platform property overrides:
2016-05-18 01:00:00,402 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 01:00:00,403 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 01:00:00,472 INFO property.AppProps (AppProps.java:getAppID(177)) - using app.id: 3A82EEE579A547F883372DE5514CB0C8
2016-05-18 01:00:00,579 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.069
2016-05-18 01:00:00,581 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 01:00:00,582 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 01:00:00,703 INFO util.Version (Version.java:printBanner(82)) - Concurrent, Inc - Cascading 3.1.0
2016-05-18 01:00:00,704 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] starting
2016-05-18 01:00:00,704 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] source: Hfs["TextDelimited[['number', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/classes/test/data/lhs+rhs-cross-x2.txt"]
2016-05-18 01:00:00,704 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] sink: PartitionTap["Hfs["TextDelimited[['upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testPartitionTapTextDelimited/partitioned"]"]["cascading.tap.partition.DelimitedPartition@798387d2"]["[]"]
2016-05-18 01:00:00,705 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] parallel execution of steps is enabled: true
2016-05-18 01:00:00,705 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] executing total steps: 1
2016-05-18 01:00:00,705 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] allocating management threads: 1
2016-05-18 01:00:00,706 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] starting step: (1/1) ...TextDelimited/partitioned
2016-05-18 01:00:01,520 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] submitted hadoop job: job_1463533194801_0001
2016-05-18 01:00:01,520 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] tracking url: http://ip-10-158-37-203.ec2.internal:37853/proxy/application_1463533194801_0001/
2016-05-18 01:00:17,645 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0001_01_000002 is : 143
2016-05-18 01:00:17,732 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0001_01_000003 is : 143
2016-05-18 01:00:18,140 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0001_01_000005 is : 143
2016-05-18 01:00:18,228 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0001_01_000004 is : 143
2016-05-18 01:00:19,516 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testPartitionTapTextDelimited/partitioned/_temporary
2016-05-18 01:00:19,519 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [partition] completed in: 00:18.805, using cpu time: 00:05.130
2016-05-18 01:00:19,541 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 01:00:19,541 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 01:00:19,593 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.043
2016-05-18 01:00:19,595 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 01:00:19,596 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 01:00:19,632 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] starting
2016-05-18 01:00:19,633 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] source: PartitionTap["Hfs["TextDelimited[['upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testPartitionTapTextDelimited/partitioned"]"]["cascading.tap.partition.DelimitedPartition@798387d2"]["[]"]
2016-05-18 01:00:19,633 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] sink: Hfs["TextDelimited[['number', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testPartitionTapTextDelimited/final"]
2016-05-18 01:00:19,634 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] parallel execution of steps is enabled: true
2016-05-18 01:00:19,634 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] executing total steps: 1
2016-05-18 01:00:19,634 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] allocating management threads: 1
2016-05-18 01:00:19,636 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] starting step: (1/1) ...ionTapTextDelimited/final
2016-05-18 01:00:19,893 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] submitted hadoop job: job_1463533194801_0002
2016-05-18 01:00:19,893 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] tracking url: http://ip-10-158-37-203.ec2.internal:37853/proxy/application_1463533194801_0002/
2016-05-18 01:00:57,763 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000003 is : 143
2016-05-18 01:00:58,495 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000008 is : 143
2016-05-18 01:00:59,188 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000010 is : 143
2016-05-18 01:00:59,883 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000004 is : 143
2016-05-18 01:01:00,099 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000005 is : 143
2016-05-18 01:01:03,497 WARN containermanager.ContainerManagerImpl (ContainerManagerImpl.java:handle(831)) - Event EventType: KILL_CONTAINER sent to absent container container_1463533194801_0002_01_000028
2016-05-18 01:01:03,497 WARN containermanager.ContainerManagerImpl (ContainerManagerImpl.java:handle(831)) - Event EventType: KILL_CONTAINER sent to absent container container_1463533194801_0002_01_000029
2016-05-18 01:01:22,029 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000017 is : 143
2016-05-18 01:01:25,487 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000018 is : 143
2016-05-18 01:01:26,657 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000020 is : 143
2016-05-18 01:01:26,779 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000019 is : 143
2016-05-18 01:01:27,080 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000023 is : 143
2016-05-18 01:01:27,663 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000021 is : 143
2016-05-18 01:01:27,731 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000022 is : 143
2016-05-18 01:01:27,783 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000024 is : 143
2016-05-18 01:01:27,916 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000027 is : 143
2016-05-18 01:01:28,023 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000025 is : 143
2016-05-18 01:01:28,156 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0002_01_000026 is : 143
2016-05-18 01:01:29,532 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testPartitionTapTextDelimited/final/_temporary
2016-05-18 01:01:29,534 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [copy] completed in: 01:09.894, using cpu time: 00:29.310
2016-05-18 01:01:30,079 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testPartitionTapTextDelimited/final
2016-05-18 01:01:30,146 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testPartitionTapTextDelimited/partitioned
2016-05-18 01:01:30,368 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 01:01:30,369 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 01:01:30,448 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.071
2016-05-18 01:01:30,450 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-18 01:01:30,450 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 01:01:30,485 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 01:01:30,485 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/classes/test/data/lhs.txt"]
2016-05-18 01:01:30,485 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testSideFileCollector/sidefile/direct"]
2016-05-18 01:01:30,486 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: true
2016-05-18 01:01:30,486 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 01:01:30,486 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 01:01:30,488 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...Collector/sidefile/direct
2016-05-18 01:01:30,661 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_1463533194801_0003
2016-05-18 01:01:30,661 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://ip-10-158-37-203.ec2.internal:37853/proxy/application_1463533194801_0003/
2016-05-18 01:01:47,911 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0003_01_000002 is : 143
2016-05-18 01:01:48,342 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0003_01_000004 is : 143
2016-05-18 01:01:48,569 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0003_01_000005 is : 143
2016-05-18 01:01:48,873 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0003_01_000003 is : 143
2016-05-18 01:01:54,715 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0003_01_000006 is : 143
2016-05-18 01:01:55,966 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testSideFileCollector/sidefile/direct/_temporary
2016-05-18 01:01:55,968 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:25.478, using cpu time: 00:05.070
2016-05-18 01:01:56,029 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testSideFileCollector/sidefile/direct
2016-05-18 01:01:56,034 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testSideFileCollector/sidefile/indirect
2016-05-18 01:01:56,080 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 01:01:56,081 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 01:01:56,115 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.029
2016-05-18 01:01:56,118 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 01:01:56,118 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 01:01:56,130 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] starting
2016-05-18 01:01:56,131 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/classes/test/data/unexpectedEndOfFile.gz"]
2016-05-18 01:01:56,131 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] sink: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testTupleEntrySchemeIteratorExceptionHandling/testTupleEntrySchemeIteratorExceptionHandling"]
2016-05-18 01:01:56,132 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] parallel execution of steps is enabled: true
2016-05-18 01:01:56,132 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] executing total steps: 1
2016-05-18 01:01:56,132 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] allocating management threads: 1
2016-05-18 01:01:56,133 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] starting step: (1/1) ...IteratorExceptionHandling
2016-05-18 01:01:56,335 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] submitted hadoop job: job_1463533194801_0004
2016-05-18 01:01:56,335 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] tracking url: http://ip-10-158-37-203.ec2.internal:37853/proxy/application_1463533194801_0004/
2016-05-18 01:02:07,626 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0004_01_000002 is : 143
2016-05-18 01:02:08,927 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testTupleEntrySchemeIteratorExceptionHandling/testTupleEntrySchemeIteratorExceptionHandling/_temporary
2016-05-18 01:02:08,929 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] completed in: 00:12.790, using cpu time: 00:00.900
2016-05-18 01:02:08,957 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testTupleEntrySchemeIteratorExceptionHandling/testTupleEntrySchemeIteratorExceptionHandling
2016-05-18 01:02:08,999 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 01:02:09,000 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 01:02:09,041 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.037
2016-05-18 01:02:09,044 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 01:02:09,044 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 01:02:09,055 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 01:02:09,055 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-18 01:02:09,055 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink: Hfs["TextLine[['line']->['second', 'first', 'third']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testSinkDeclaredFields/declaredsinks"]
2016-05-18 01:02:09,056 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: true
2016-05-18 01:02:09,056 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 01:02:09,057 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 01:02:09,058 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...laredFields/declaredsinks
2016-05-18 01:02:09,259 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_1463533194801_0005
2016-05-18 01:02:09,259 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://ip-10-158-37-203.ec2.internal:37853/proxy/application_1463533194801_0005/
2016-05-18 01:02:26,998 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0005_01_000003 is : 143
2016-05-18 01:02:27,202 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0005_01_000004 is : 143
2016-05-18 01:02:27,759 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0005_01_000002 is : 143
2016-05-18 01:02:28,060 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0005_01_000005 is : 143
2016-05-18 01:02:29,311 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testSinkDeclaredFields/declaredsinks/_temporary
2016-05-18 01:02:29,313 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:20.253, using cpu time: 00:03.960
2016-05-18 01:02:29,352 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testSinkDeclaredFields/declaredsinks
2016-05-18 01:02:29,395 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 01:02:29,396 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 01:02:29,435 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.033
2016-05-18 01:02:29,436 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 01:02:29,436 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 01:02:29,455 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 01:02:29,455 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/classes/test/data/lower+upper.txt"]
2016-05-18 01:02:29,456 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink: MultiSinkTap[[Hfs["TextLine[['offset', 'line']->['number', 'lower']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testMultiSinkTap/multisink/lhs"], Hfs["TextLine[['offset', 'line']->['number', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testMultiSinkTap/multisink/rhs"]]]
2016-05-18 01:02:29,457 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: true
2016-05-18 01:02:29,457 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 01:02:29,457 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 01:02:29,458 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...A39EC43FC9AD4F8EBBA727F28
2016-05-18 01:02:29,536 WARN blockmanagement.BlockPlacementPolicy (BlockPlacementPolicyDefault.java:chooseTarget(309)) - Failed to place enough replicas, still in need of 1 to reach 4. For more information, please enable DEBUG log level on org.apache.hadoop.hdfs.server.blockmanagement.BlockPlacementPolicy
2016-05-18 01:02:29,610 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_1463533194801_0006
2016-05-18 01:02:29,610 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://ip-10-158-37-203.ec2.internal:37853/proxy/application_1463533194801_0006/
2016-05-18 01:02:47,315 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0006_01_000002 is : 143
2016-05-18 01:02:47,689 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0006_01_000003 is : 143
2016-05-18 01:02:48,565 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0006_01_000005 is : 143
2016-05-18 01:02:48,657 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0006_01_000006 is : 143
2016-05-18 01:02:48,822 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0006_01_000004 is : 143
2016-05-18 01:02:50,056 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:20.593, using cpu time: 00:05.400
2016-05-18 01:02:50,119 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testMultiSinkTap/multisink/rhs
2016-05-18 01:02:50,133 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testMultiSinkTap/multisink/lhs
2016-05-18 01:02:50,163 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 01:02:50,164 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 01:02:50,195 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.026
2016-05-18 01:02:50,196 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 01:02:50,196 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 01:02:50,209 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 01:02:50,210 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-18 01:02:50,210 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink: Hfs["TextDelimited[['first', 'second', 'third']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testSinkUnknown/unknownsinks"]
2016-05-18 01:02:50,210 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: true
2016-05-18 01:02:50,210 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 01:02:50,210 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 01:02:50,212 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...tSinkUnknown/unknownsinks
2016-05-18 01:02:50,370 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_1463533194801_0007
2016-05-18 01:02:50,370 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://ip-10-158-37-203.ec2.internal:37853/proxy/application_1463533194801_0007/
2016-05-18 01:03:07,621 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0007_01_000004 is : 143
2016-05-18 01:03:07,707 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0007_01_000003 is : 143
2016-05-18 01:03:08,091 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0007_01_000005 is : 143
2016-05-18 01:03:08,794 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0007_01_000002 is : 143
2016-05-18 01:03:10,046 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testSinkUnknown/unknownsinks/_temporary
2016-05-18 01:03:10,047 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:19.835, using cpu time: 00:04.010
2016-05-18 01:03:10,087 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testSinkUnknown/unknownsinks
2016-05-18 01:03:10,110 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 01:03:10,110 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 01:03:10,147 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.034
2016-05-18 01:03:10,148 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 01:03:10,148 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 01:03:10,163 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] starting
2016-05-18 01:03:10,163 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/classes/test/data/unexpectedEndOfFile.gz"]
2016-05-18 01:03:10,163 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] sink: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testTupleEntrySchemeIteratorEOFException/testTupleEntrySchemeIteratorEOFException"]
2016-05-18 01:03:10,164 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] parallel execution of steps is enabled: true
2016-05-18 01:03:10,164 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] executing total steps: 1
2016-05-18 01:03:10,164 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] allocating management threads: 1
2016-05-18 01:03:10,166 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] starting step: (1/1) ...chemeIteratorEOFException
2016-05-18 01:03:10,320 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] submitted hadoop job: job_1463533194801_0008
2016-05-18 01:03:10,320 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] tracking url: http://ip-10-158-37-203.ec2.internal:37853/proxy/application_1463533194801_0008/
2016-05-18 01:03:21,437 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0008_01_000002 is : 143
2016-05-18 01:03:26,707 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0008_01_000003 is : 143
2016-05-18 01:03:32,448 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0008_01_000004 is : 143
2016-05-18 01:03:37,866 WARN nodemanager.DefaultContainerExecutor (DefaultContainerExecutor.java:launchContainer(207)) - Exit code from container container_1463533194801_0008_01_000005 is : 143
2016-05-18 01:03:38,986 WARN flow.Flow (BaseFlow.java:logWarn(1516)) - [] hadoop job job_1463533194801_0008 state at FAILED
2016-05-18 01:03:38,990 WARN flow.Flow (BaseFlow.java:logWarn(1516)) - [] failure info: Task failed task_1463533194801_0008_m_000000
Job failed as tasks failed. failedMaps:1 failedReduces:0
2016-05-18 01:03:39,019 WARN flow.Flow (BaseFlow.java:logWarn(1516)) - [] task completion events identify failed tasks
2016-05-18 01:03:39,019 WARN flow.Flow (BaseFlow.java:logWarn(1516)) - [] task completion events count: 4
2016-05-18 01:03:39,020 WARN flow.Flow (BaseFlow.java:logWarn(1516)) - [] event = Task Id : attempt_1463533194801_0008_m_000000_0, Status : FAILED
2016-05-18 01:03:39,020 WARN flow.Flow (BaseFlow.java:logWarn(1516)) - [] event = Task Id : attempt_1463533194801_0008_m_000000_1, Status : FAILED
2016-05-18 01:03:39,020 WARN flow.Flow (BaseFlow.java:logWarn(1516)) - [] event = Task Id : attempt_1463533194801_0008_m_000000_2, Status : FAILED
2016-05-18 01:03:39,020 WARN flow.Flow (BaseFlow.java:logWarn(1516)) - [] event = Task Id : attempt_1463533194801_0008_m_000000_3, Status : TIPFAILED
2016-05-18 01:03:39,079 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] stopping all jobs
2016-05-18 01:03:39,081 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] stopping: (1/1) ...chemeIteratorEOFException
2016-05-18 01:03:39,083 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] stopped all jobs
2016-05-18 01:03:39,085 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output/hadoop2-mr1/tapplatform/testTupleEntrySchemeIteratorEOFException/testTupleEntrySchemeIteratorEOFException/_temporary
2016-05-18 01:03:39,087 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [] completed in: 00:28.920
2016-05-18 01:03:39,088 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.3.x/build/test/output//hadoop2-mr1/tapplatform/testTupleEntrySchemeIteratorEOFException/testTupleEntrySchemeIteratorEOFException