2016-01-14 23:26:05,603 INFO platform.PlatformRunner (PlatformRunner.java:getPlatformClassesFromClasspath(195)) - found 1 test platforms from classpath
2016-01-14 23:26:05,619 INFO platform.PlatformRunner (PlatformRunner.java:addPlatform(244)) - adding test: cascading.BufferPipesPlatformTest, with platform: hadoop
2016-01-14 23:26:05,644 INFO hadoop.HadoopPlatform (HadoopPlatform.java:setUp(127)) - not using cluster
2016-01-14 23:26:05,907 INFO platform.TestPlatform (TestPlatform.java:getGlobalProperties(73)) - platform property overrides:
2016-01-14 23:26:06,014 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 23:26:06,016 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.0.4/ab50b225eb80fe7b8e59368476a4bfb0fa1a0ef5/cascading-hadoop-3.0.4.jar
2016-01-14 23:26:06,134 INFO property.AppProps (AppProps.java:getAppID(177)) - using app.id: 15C01EC5DF4B451DB129059A6BAD58E1
2016-01-14 23:26:06,320 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.144
2016-01-14 23:26:06,324 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 23:26:06,324 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-01-14 23:26:06,506 INFO util.Version (Version.java:printBanner(82)) - Concurrent, Inc - Cascading 3.0.4
2016-01-14 23:26:06,508 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 23:26:06,510 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:06,511 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testIdentityBuffer/identity"]
2016-01-14 23:26:06,511 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 23:26:06,512 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 23:26:06,514 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 23:26:06,516 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...stIdentityBuffer/identity
2016-01-14 23:26:06,555 WARN util.NativeCodeLoader (NativeCodeLoader.java:<clinit>(52)) - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
2016-01-14 23:26:06,615 WARN snappy.LoadSnappy (LoadSnappy.java:<clinit>(46)) - Snappy native library not loaded
2016-01-14 23:26:06,855 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local1097814713_0001
2016-01-14 23:26:06,856 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 23:26:06,956 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt
2016-01-14 23:26:07,093 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 23:26:07,094 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 23:26:07,106 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: A057A9ABDCED4C59B41E374FF78BF551, ordinal: 0
2016-01-14 23:26:07,153 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:07,154 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:07,155 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A057A9ABDCED4C59B41E374FF78BF551, mem on start (mb), free: 83, total: 223, max: 672
2016-01-14 23:26:07,167 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A057A9ABDCED4C59B41E374FF78BF551, mem on close (mb), free: 82, total: 223, max: 672
2016-01-14 23:26:07,201 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 23:26:07,201 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 23:26:07,214 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: B481F5D23BE94C6A990B8812967CFC17, ordinal: 1
2016-01-14 23:26:07,218 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:07,219 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testIdentityBuffer/identity"]
2016-01-14 23:26:07,219 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B481F5D23BE94C6A990B8812967CFC17, mem on start (mb), free: 81, total: 223, max: 672
2016-01-14 23:26:07,235 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B481F5D23BE94C6A990B8812967CFC17, mem on close (mb), free: 80, total: 223, max: 672
2016-01-14 23:26:07,255 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testIdentityBuffer/identity/_temporary
2016-01-14 23:26:07,256 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.746
2016-01-14 23:26:07,294 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output//hadoop/bufferpipesplatform/testIdentityBuffer/identity
2016-01-14 23:26:07,319 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 23:26:07,320 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.0.4/ab50b225eb80fe7b8e59368476a4bfb0fa1a0ef5/cascading-hadoop-3.0.4.jar
2016-01-14 23:26:07,389 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.063
2016-01-14 23:26:07,391 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 23:26:07,391 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-01-14 23:26:07,419 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 23:26:07,419 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:07,419 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferForward/firstn"]
2016-01-14 23:26:07,419 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 23:26:07,420 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 23:26:07,420 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 23:26:07,420 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...irstNBufferForward/firstn
2016-01-14 23:26:07,550 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local529367334_0002
2016-01-14 23:26:07,550 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 23:26:07,557 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt
2016-01-14 23:26:07,663 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 23:26:07,663 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 23:26:07,675 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: A511CEA56A524A63A3301E2ED2822C65, ordinal: 0
2016-01-14 23:26:07,676 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:07,676 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:07,677 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A511CEA56A524A63A3301E2ED2822C65, mem on start (mb), free: 61, total: 318, max: 672
2016-01-14 23:26:07,681 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A511CEA56A524A63A3301E2ED2822C65, mem on close (mb), free: 61, total: 318, max: 672
2016-01-14 23:26:07,711 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 23:26:07,711 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 23:26:07,725 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: FCE7771D37B1454BAB49BE6DBC7FD419, ordinal: 1
2016-01-14 23:26:07,726 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:07,726 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferForward/firstn"]
2016-01-14 23:26:07,726 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FCE7771D37B1454BAB49BE6DBC7FD419, mem on start (mb), free: 171, total: 377, max: 672
2016-01-14 23:26:07,734 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FCE7771D37B1454BAB49BE6DBC7FD419, mem on close (mb), free: 171, total: 377, max: 672
2016-01-14 23:26:07,750 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferForward/firstn/_temporary
2016-01-14 23:26:07,750 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.331
2016-01-14 23:26:07,797 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output//hadoop/bufferpipesplatform/testFirstNBufferForward/firstn
2016-01-14 23:26:07,821 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 23:26:07,822 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.0.4/ab50b225eb80fe7b8e59368476a4bfb0fa1a0ef5/cascading-hadoop-3.0.4.jar
2016-01-14 23:26:07,925 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.095
2016-01-14 23:26:07,927 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 23:26:07,927 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-01-14 23:26:07,953 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 23:26:07,953 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:07,954 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer/simple"]
2016-01-14 23:26:07,954 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 23:26:07,954 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 23:26:07,955 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 23:26:07,956 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...m/testSimpleBuffer/simple
2016-01-14 23:26:08,003 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local551194252_0003
2016-01-14 23:26:08,004 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 23:26:08,008 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt
2016-01-14 23:26:08,293 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 23:26:08,293 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 23:26:08,303 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: BC135729F3E44056BF4D44A02C276A0B, ordinal: 0
2016-01-14 23:26:08,304 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:08,304 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:08,304 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: BC135729F3E44056BF4D44A02C276A0B, mem on start (mb), free: 306, total: 412, max: 672
2016-01-14 23:26:08,308 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: BC135729F3E44056BF4D44A02C276A0B, mem on close (mb), free: 306, total: 412, max: 672
2016-01-14 23:26:08,320 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 23:26:08,321 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 23:26:08,330 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: F3210B64F489472BA27F20D1C800255D, ordinal: 1
2016-01-14 23:26:08,331 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:08,331 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer/simple"]
2016-01-14 23:26:08,332 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F3210B64F489472BA27F20D1C800255D, mem on start (mb), free: 304, total: 412, max: 672
2016-01-14 23:26:08,338 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F3210B64F489472BA27F20D1C800255D, mem on close (mb), free: 304, total: 412, max: 672
2016-01-14 23:26:08,353 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer/simple/_temporary
2016-01-14 23:26:08,353 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.399
2016-01-14 23:26:08,372 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output//hadoop/bufferpipesplatform/testSimpleBuffer/simple
2016-01-14 23:26:08,385 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 23:26:08,386 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.0.4/ab50b225eb80fe7b8e59368476a4bfb0fa1a0ef5/cascading-hadoop-3.0.4.jar
2016-01-14 23:26:08,479 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.086
2016-01-14 23:26:08,480 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 23:26:08,481 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-01-14 23:26:08,511 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] starting
2016-01-14 23:26:08,512 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/upper.txt"]
2016-01-14 23:26:08,512 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lower.txt"]
2016-01-14 23:26:08,513 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testJoinerClosure/cogroup"]
2016-01-14 23:26:08,513 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] parallel execution of steps is enabled: false
2016-01-14 23:26:08,513 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] executing total steps: 1
2016-01-14 23:26:08,513 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] allocating management threads: 1
2016-01-14 23:26:08,514 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] starting step: (1/1) ...testJoinerClosure/cogroup
2016-01-14 23:26:08,561 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] submitted hadoop job: job_local1110826610_0004
2016-01-14 23:26:08,561 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] tracking url: http://localhost:8080/
2016-01-14 23:26:08,566 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/upper.txt
2016-01-14 23:26:08,665 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 23:26:08,666 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 23:26:08,674 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B3508736B85D408B8BFA421D1319668E, ordinal: 0
2016-01-14 23:26:08,676 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/upper.txt"]
2016-01-14 23:26:08,676 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: CoGroup(lower*upper)[by: lower:[{1}:'num'] upper:[{1}:'num']]
2016-01-14 23:26:08,676 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B3508736B85D408B8BFA421D1319668E, mem on start (mb), free: 459, total: 567, max: 672
2016-01-14 23:26:08,678 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B3508736B85D408B8BFA421D1319668E, mem on close (mb), free: 459, total: 567, max: 672
2016-01-14 23:26:08,685 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lower.txt
2016-01-14 23:26:08,700 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 23:26:08,700 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 23:26:08,709 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B3508736B85D408B8BFA421D1319668E, ordinal: 0
2016-01-14 23:26:08,710 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lower.txt"]
2016-01-14 23:26:08,710 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: CoGroup(lower*upper)[by: lower:[{1}:'num'] upper:[{1}:'num']]
2016-01-14 23:26:08,710 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B3508736B85D408B8BFA421D1319668E, mem on start (mb), free: 357, total: 567, max: 672
2016-01-14 23:26:08,712 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B3508736B85D408B8BFA421D1319668E, mem on close (mb), free: 357, total: 567, max: 672
2016-01-14 23:26:08,725 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 23:26:08,725 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 23:26:08,732 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: D427B29AD47F466C8A6B677E3CC90D9F, ordinal: 1
2016-01-14 23:26:08,733 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: CoGroup(lower*upper)[by: lower:[{1}:'num'] upper:[{1}:'num']]
2016-01-14 23:26:08,733 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testJoinerClosure/cogroup"]
2016-01-14 23:26:08,733 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D427B29AD47F466C8A6B677E3CC90D9F, mem on start (mb), free: 355, total: 567, max: 672
2016-01-14 23:26:08,743 INFO collect.SpillableTupleList (SpillableTupleList.java:getCodecClass(89)) - attempting to load codec: org.apache.hadoop.io.compress.GzipCodec
2016-01-14 23:26:08,743 INFO collect.SpillableTupleList (SpillableTupleList.java:getCodecClass(94)) - found codec: org.apache.hadoop.io.compress.GzipCodec
2016-01-14 23:26:08,749 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D427B29AD47F466C8A6B677E3CC90D9F, mem on close (mb), free: 355, total: 567, max: 672
2016-01-14 23:26:08,769 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testJoinerClosure/cogroup/_temporary
2016-01-14 23:26:08,769 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] completed in: 00:00.257
2016-01-14 23:26:08,789 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output//hadoop/bufferpipesplatform/testJoinerClosure/cogroup
2016-01-14 23:26:08,800 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 23:26:08,801 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.0.4/ab50b225eb80fe7b8e59368476a4bfb0fa1a0ef5/cascading-hadoop-3.0.4.jar
2016-01-14 23:26:08,849 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.044
2016-01-14 23:26:08,850 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 23:26:08,851 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-01-14 23:26:08,870 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 23:26:08,870 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:08,872 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer2/simple2"]
2016-01-14 23:26:08,872 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 23:26:08,873 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 23:26:08,874 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 23:26:08,875 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...testSimpleBuffer2/simple2
2016-01-14 23:26:08,924 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local974746533_0005
2016-01-14 23:26:08,925 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 23:26:08,932 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt
2016-01-14 23:26:08,960 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 23:26:08,964 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 23:26:08,974 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B0555D9F13F74691B9F9C759DC91AB80, ordinal: 0
2016-01-14 23:26:08,975 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:08,976 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:08,976 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B0555D9F13F74691B9F9C759DC91AB80, mem on start (mb), free: 380, total: 588, max: 672
2016-01-14 23:26:08,980 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B0555D9F13F74691B9F9C759DC91AB80, mem on close (mb), free: 380, total: 588, max: 672
2016-01-14 23:26:08,990 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 23:26:08,990 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 23:26:08,997 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: F9E9788C124F4C12B9397C784949CE29, ordinal: 1
2016-01-14 23:26:08,998 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:08,998 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer2/simple2"]
2016-01-14 23:26:08,998 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F9E9788C124F4C12B9397C784949CE29, mem on start (mb), free: 378, total: 588, max: 672
2016-01-14 23:26:09,003 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F9E9788C124F4C12B9397C784949CE29, mem on close (mb), free: 378, total: 588, max: 672
2016-01-14 23:26:09,020 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer2/simple2/_temporary
2016-01-14 23:26:09,021 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.150
2016-01-14 23:26:09,039 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output//hadoop/bufferpipesplatform/testSimpleBuffer2/simple2
2016-01-14 23:26:09,052 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 23:26:09,053 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.0.4/ab50b225eb80fe7b8e59368476a4bfb0fa1a0ef5/cascading-hadoop-3.0.4.jar
2016-01-14 23:26:09,095 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.038
2016-01-14 23:26:09,098 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 23:26:09,099 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-01-14 23:26:09,120 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 23:26:09,120 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lower+upper.txt"]
2016-01-14 23:26:09,120 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer3/simple3"]
2016-01-14 23:26:09,121 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 23:26:09,121 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 23:26:09,121 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 23:26:09,122 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...testSimpleBuffer3/simple3
2016-01-14 23:26:09,168 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local226333692_0006
2016-01-14 23:26:09,169 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 23:26:09,173 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lower+upper.txt
2016-01-14 23:26:09,263 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 23:26:09,263 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 23:26:09,271 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: FF9E8B7D6EDE42CB9A8BD67FEB4023F8, ordinal: 0
2016-01-14 23:26:09,271 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lower+upper.txt"]
2016-01-14 23:26:09,272 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:09,272 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FF9E8B7D6EDE42CB9A8BD67FEB4023F8, mem on start (mb), free: 263, total: 588, max: 672
2016-01-14 23:26:09,274 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FF9E8B7D6EDE42CB9A8BD67FEB4023F8, mem on close (mb), free: 263, total: 588, max: 672
2016-01-14 23:26:09,285 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 23:26:09,285 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 23:26:09,295 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: FAB08C6B8F404F8C973CE1B859964A8D, ordinal: 1
2016-01-14 23:26:09,296 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:09,297 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer3/simple3"]
2016-01-14 23:26:09,297 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FAB08C6B8F404F8C973CE1B859964A8D, mem on start (mb), free: 262, total: 588, max: 672
2016-01-14 23:26:09,303 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FAB08C6B8F404F8C973CE1B859964A8D, mem on close (mb), free: 261, total: 588, max: 672
2016-01-14 23:26:09,314 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer3/simple3/_temporary
2016-01-14 23:26:09,314 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.193
2016-01-14 23:26:09,337 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output//hadoop/bufferpipesplatform/testSimpleBuffer3/simple3
2016-01-14 23:26:09,349 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 23:26:09,350 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.0.4/ab50b225eb80fe7b8e59368476a4bfb0fa1a0ef5/cascading-hadoop-3.0.4.jar
2016-01-14 23:26:09,394 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.041
2016-01-14 23:26:09,395 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 23:26:09,395 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-01-14 23:26:09,411 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 23:26:09,411 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:09,411 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferReverse/firstn"]
2016-01-14 23:26:09,412 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 23:26:09,412 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 23:26:09,412 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 23:26:09,413 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...irstNBufferReverse/firstn
2016-01-14 23:26:09,463 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local595199303_0007
2016-01-14 23:26:09,463 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 23:26:09,468 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt
2016-01-14 23:26:09,483 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 23:26:09,483 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 23:26:09,489 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: F1091063D4B345349706240705858F85, ordinal: 0
2016-01-14 23:26:09,490 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:09,490 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:09,490 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: F1091063D4B345349706240705858F85, mem on start (mb), free: 375, total: 589, max: 672
2016-01-14 23:26:09,494 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: F1091063D4B345349706240705858F85, mem on close (mb), free: 375, total: 589, max: 672
2016-01-14 23:26:09,503 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 23:26:09,503 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 23:26:09,509 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: EDB8B8FD36664919BDE6E5C345332942, ordinal: 1
2016-01-14 23:26:09,509 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:09,510 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferReverse/firstn"]
2016-01-14 23:26:09,510 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: EDB8B8FD36664919BDE6E5C345332942, mem on start (mb), free: 375, total: 589, max: 672
2016-01-14 23:26:09,514 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: EDB8B8FD36664919BDE6E5C345332942, mem on close (mb), free: 374, total: 589, max: 672
2016-01-14 23:26:09,528 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferReverse/firstn/_temporary
2016-01-14 23:26:09,528 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.117
2016-01-14 23:26:09,544 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output//hadoop/bufferpipesplatform/testFirstNBufferReverse/firstn
2016-01-14 23:26:09,554 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 23:26:09,554 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.0.4/ab50b225eb80fe7b8e59368476a4bfb0fa1a0ef5/cascading-hadoop-3.0.4.jar
2016-01-14 23:26:09,582 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] executed rule registry: MapReduceHadoopRuleRegistry, completed as: ILLEGAL, in: 00:00.023
2016-01-14 23:26:09,583 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] rule registry: MapReduceHadoopRuleRegistry, found assembly to be malformed
2016-01-14 23:26:09,594 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 23:26:09,595 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.0.4/ab50b225eb80fe7b8e59368476a4bfb0fa1a0ef5/cascading-hadoop-3.0.4.jar
2016-01-14 23:26:09,634 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.035
2016-01-14 23:26:09,635 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 23:26:09,635 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-01-14 23:26:09,651 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 23:26:09,652 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:09,652 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBuffer/firstn"]
2016-01-14 23:26:09,652 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 23:26:09,653 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 23:26:09,653 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 23:26:09,654 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...m/testFirstNBuffer/firstn
2016-01-14 23:26:09,695 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local62656752_0008
2016-01-14 23:26:09,696 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 23:26:09,703 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt
2016-01-14 23:26:09,815 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 23:26:09,815 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 23:26:09,820 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B74BBE664FCF4C53A32A653083E7826C, ordinal: 0
2016-01-14 23:26:09,821 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/classes/test/data/lhs.txt"]
2016-01-14 23:26:09,821 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:09,821 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B74BBE664FCF4C53A32A653083E7826C, mem on start (mb), free: 512, total: 619, max: 672
2016-01-14 23:26:09,824 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B74BBE664FCF4C53A32A653083E7826C, mem on close (mb), free: 512, total: 619, max: 672
2016-01-14 23:26:09,833 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 23:26:09,833 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 23:26:09,839 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: B8CD035AB0AF4BACBE86EDF0BED07712, ordinal: 1
2016-01-14 23:26:09,840 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 23:26:09,840 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBuffer/firstn"]
2016-01-14 23:26:09,840 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B8CD035AB0AF4BACBE86EDF0BED07712, mem on start (mb), free: 510, total: 619, max: 672
2016-01-14 23:26:09,845 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B8CD035AB0AF4BACBE86EDF0BED07712, mem on close (mb), free: 510, total: 619, max: 672
2016-01-14 23:26:09,856 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBuffer/firstn/_temporary
2016-01-14 23:26:09,856 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.204
2016-01-14 23:26:09,876 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.2.x/build/test/output//hadoop/bufferpipesplatform/testFirstNBuffer/firstn