2016-01-14 21:58:56,200 INFO platform.PlatformRunner (PlatformRunner.java:getPlatformClassesFromClasspath(195)) - found 1 test platforms from classpath
2016-01-14 21:58:56,216 INFO platform.PlatformRunner (PlatformRunner.java:addPlatform(244)) - adding test: cascading.BufferPipesPlatformTest, with platform: hadoop
2016-01-14 21:58:56,241 INFO hadoop.HadoopPlatform (HadoopPlatform.java:setUp(127)) - not using cluster
2016-01-14 21:58:56,477 INFO platform.TestPlatform (TestPlatform.java:getGlobalProperties(73)) - platform property overrides:
2016-01-14 21:58:56,582 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 21:58:56,584 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 21:58:56,700 INFO property.AppProps (AppProps.java:getAppID(177)) - using app.id: C9BF50E06DF445DAB0A176711371F227
2016-01-14 21:58:56,886 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.142
2016-01-14 21:58:56,889 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 21:58:56,890 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 21:58:57,070 INFO util.Version (Version.java:printBanner(82)) - Concurrent, Inc - Cascading 3.0.4
2016-01-14 21:58:57,072 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 21:58:57,073 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:57,073 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testIdentityBuffer/identity"]
2016-01-14 21:58:57,074 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 21:58:57,074 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 21:58:57,075 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 21:58:57,076 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...stIdentityBuffer/identity
2016-01-14 21:58:57,121 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 21:58:57,178 WARN snappy.LoadSnappy (LoadSnappy.java:<clinit>(46)) - Snappy native library not loaded
2016-01-14 21:58:57,447 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local_0001
2016-01-14 21:58:57,449 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 21:58:57,529 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt
2016-01-14 21:58:57,660 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 21:58:57,661 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 21:58:57,673 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: D1612954870147DABB5ABFF879C11AF6, ordinal: 0
2016-01-14 21:58:57,718 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:57,718 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:57,719 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: D1612954870147DABB5ABFF879C11AF6, mem on start (mb), free: 84, total: 223, max: 672
2016-01-14 21:58:57,732 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: D1612954870147DABB5ABFF879C11AF6, mem on close (mb), free: 83, total: 223, max: 672
2016-01-14 21:58:57,767 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 21:58:57,768 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 21:58:57,781 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: A0586D52596D47BD8C90B78437F0B593, ordinal: 1
2016-01-14 21:58:57,785 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:57,786 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testIdentityBuffer/identity"]
2016-01-14 21:58:57,786 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A0586D52596D47BD8C90B78437F0B593, mem on start (mb), free: 81, total: 223, max: 672
2016-01-14 21:58:57,803 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A0586D52596D47BD8C90B78437F0B593, mem on close (mb), free: 81, total: 223, max: 672
2016-01-14 21:58:57,816 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testIdentityBuffer/identity/_temporary
2016-01-14 21:58:57,818 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.743
2016-01-14 21:58:57,856 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output//hadoop/bufferpipesplatform/testIdentityBuffer/identity
2016-01-14 21:58:57,883 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 21:58:57,884 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 21:58:57,979 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.087
2016-01-14 21:58:57,981 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 21:58:57,981 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 21:58:58,010 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 21:58:58,011 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:58,011 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferForward/firstn"]
2016-01-14 21:58:58,012 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 21:58:58,012 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 21:58:58,012 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 21:58:58,013 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...irstNBufferForward/firstn
2016-01-14 21:58:58,108 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local_0002
2016-01-14 21:58:58,109 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 21:58:58,114 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt
2016-01-14 21:58:58,215 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 21:58:58,215 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 21:58:58,227 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B5FC1D6E7B6344CBBC72BDFC13F3E21F, ordinal: 0
2016-01-14 21:58:58,228 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:58,229 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:58,229 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B5FC1D6E7B6344CBBC72BDFC13F3E21F, mem on start (mb), free: 61, total: 318, max: 672
2016-01-14 21:58:58,234 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B5FC1D6E7B6344CBBC72BDFC13F3E21F, mem on close (mb), free: 61, total: 318, max: 672
2016-01-14 21:58:58,263 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 21:58:58,264 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 21:58:58,278 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: FD5C7778DA6D4EAF9D27E98CF1E14F44, ordinal: 1
2016-01-14 21:58:58,279 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:58,280 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferForward/firstn"]
2016-01-14 21:58:58,280 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FD5C7778DA6D4EAF9D27E98CF1E14F44, mem on start (mb), free: 114, total: 318, max: 672
2016-01-14 21:58:58,288 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FD5C7778DA6D4EAF9D27E98CF1E14F44, mem on close (mb), free: 113, total: 318, max: 672
2016-01-14 21:58:58,304 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferForward/firstn/_temporary
2016-01-14 21:58:58,305 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.294
2016-01-14 21:58:58,339 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output//hadoop/bufferpipesplatform/testFirstNBufferForward/firstn
2016-01-14 21:58:58,357 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 21:58:58,358 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 21:58:58,432 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.068
2016-01-14 21:58:58,434 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 21:58:58,435 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 21:58:58,459 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 21:58:58,460 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:58,460 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer/simple"]
2016-01-14 21:58:58,461 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 21:58:58,462 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 21:58:58,462 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 21:58:58,463 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...m/testSimpleBuffer/simple
2016-01-14 21:58:58,509 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local_0003
2016-01-14 21:58:58,510 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 21:58:58,514 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt
2016-01-14 21:58:58,616 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 21:58:58,616 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 21:58:58,627 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: AE1DE211C35743EC8EA44FE9718E09C0, ordinal: 0
2016-01-14 21:58:58,628 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:58,628 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:58,628 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: AE1DE211C35743EC8EA44FE9718E09C0, mem on start (mb), free: 95, total: 414, max: 672
2016-01-14 21:58:58,632 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: AE1DE211C35743EC8EA44FE9718E09C0, mem on close (mb), free: 95, total: 414, max: 672
2016-01-14 21:58:58,642 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 21:58:58,642 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 21:58:58,652 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: D26CA53E4B3646C48735745BA786C11A, ordinal: 1
2016-01-14 21:58:58,653 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:58,653 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer/simple"]
2016-01-14 21:58:58,654 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D26CA53E4B3646C48735745BA786C11A, mem on start (mb), free: 93, total: 414, max: 672
2016-01-14 21:58:58,661 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D26CA53E4B3646C48735745BA786C11A, mem on close (mb), free: 93, total: 414, max: 672
2016-01-14 21:58:58,674 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer/simple/_temporary
2016-01-14 21:58:58,674 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.214
2016-01-14 21:58:58,693 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output//hadoop/bufferpipesplatform/testSimpleBuffer/simple
2016-01-14 21:58:58,704 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 21:58:58,705 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 21:58:58,801 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.092
2016-01-14 21:58:58,803 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 21:58:58,804 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 21:58:58,834 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] starting
2016-01-14 21:58:58,834 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/upper.txt"]
2016-01-14 21:58:58,834 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lower.txt"]
2016-01-14 21:58:58,834 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testJoinerClosure/cogroup"]
2016-01-14 21:58:58,835 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] parallel execution of steps is enabled: false
2016-01-14 21:58:58,835 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] executing total steps: 1
2016-01-14 21:58:58,835 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] allocating management threads: 1
2016-01-14 21:58:58,836 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] starting step: (1/1) ...testJoinerClosure/cogroup
2016-01-14 21:58:58,894 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] submitted hadoop job: job_local_0004
2016-01-14 21:58:58,894 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] tracking url: http://localhost:8080/
2016-01-14 21:58:58,898 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/upper.txt
2016-01-14 21:58:58,995 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 21:58:58,996 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 21:58:59,004 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B0455039A0284C62BBCCA35C1914F716, ordinal: 0
2016-01-14 21:58:59,006 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/upper.txt"]
2016-01-14 21:58:59,006 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: CoGroup(lower*upper)[by: lower:[{1}:'num'] upper:[{1}:'num']]
2016-01-14 21:58:59,006 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B0455039A0284C62BBCCA35C1914F716, mem on start (mb), free: 74, total: 509, max: 672
2016-01-14 21:58:59,008 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B0455039A0284C62BBCCA35C1914F716, mem on close (mb), free: 74, total: 509, max: 672
2016-01-14 21:58:59,017 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lower.txt
2016-01-14 21:58:59,114 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 21:58:59,114 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 21:58:59,126 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B0455039A0284C62BBCCA35C1914F716, ordinal: 0
2016-01-14 21:58:59,127 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lower.txt"]
2016-01-14 21:58:59,127 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: CoGroup(lower*upper)[by: lower:[{1}:'num'] upper:[{1}:'num']]
2016-01-14 21:58:59,128 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B0455039A0284C62BBCCA35C1914F716, mem on start (mb), free: 35, total: 572, max: 672
2016-01-14 21:58:59,130 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B0455039A0284C62BBCCA35C1914F716, mem on close (mb), free: 35, total: 572, max: 672
2016-01-14 21:58:59,143 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 21:58:59,144 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 21:58:59,151 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: FC36DE640E974507B9B4EA6AB77BAB09, ordinal: 1
2016-01-14 21:58:59,152 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: CoGroup(lower*upper)[by: lower:[{1}:'num'] upper:[{1}:'num']]
2016-01-14 21:58:59,152 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testJoinerClosure/cogroup"]
2016-01-14 21:58:59,153 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FC36DE640E974507B9B4EA6AB77BAB09, mem on start (mb), free: 34, total: 572, max: 672
2016-01-14 21:58:59,162 INFO collect.SpillableTupleList (SpillableTupleList.java:getCodecClass(89)) - attempting to load codec: org.apache.hadoop.io.compress.GzipCodec
2016-01-14 21:58:59,162 INFO collect.SpillableTupleList (SpillableTupleList.java:getCodecClass(94)) - found codec: org.apache.hadoop.io.compress.GzipCodec
2016-01-14 21:58:59,168 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FC36DE640E974507B9B4EA6AB77BAB09, mem on close (mb), free: 33, total: 572, max: 672
2016-01-14 21:58:59,179 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testJoinerClosure/cogroup/_temporary
2016-01-14 21:58:59,180 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] completed in: 00:00.345
2016-01-14 21:58:59,201 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output//hadoop/bufferpipesplatform/testJoinerClosure/cogroup
2016-01-14 21:58:59,210 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 21:58:59,211 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 21:58:59,262 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.047
2016-01-14 21:58:59,263 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 21:58:59,263 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 21:58:59,291 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 21:58:59,292 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:59,292 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer2/simple2"]
2016-01-14 21:58:59,293 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 21:58:59,294 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 21:58:59,294 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 21:58:59,296 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...testSimpleBuffer2/simple2
2016-01-14 21:58:59,340 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local_0005
2016-01-14 21:58:59,341 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 21:58:59,348 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt
2016-01-14 21:58:59,401 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 21:58:59,401 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 21:58:59,413 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: C050DE07E4A44A26964E654F3E6FB242, ordinal: 0
2016-01-14 21:58:59,415 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:59,415 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:59,416 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C050DE07E4A44A26964E654F3E6FB242, mem on start (mb), free: 46, total: 630, max: 672
2016-01-14 21:58:59,419 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C050DE07E4A44A26964E654F3E6FB242, mem on close (mb), free: 46, total: 630, max: 672
2016-01-14 21:58:59,430 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 21:58:59,430 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 21:58:59,439 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: A2A892A99BBF42AC901E11CC9BE1A327, ordinal: 1
2016-01-14 21:58:59,440 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:59,441 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer2/simple2"]
2016-01-14 21:58:59,441 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A2A892A99BBF42AC901E11CC9BE1A327, mem on start (mb), free: 44, total: 630, max: 672
2016-01-14 21:58:59,448 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A2A892A99BBF42AC901E11CC9BE1A327, mem on close (mb), free: 44, total: 630, max: 672
2016-01-14 21:58:59,459 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer2/simple2/_temporary
2016-01-14 21:58:59,459 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.167
2016-01-14 21:58:59,482 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output//hadoop/bufferpipesplatform/testSimpleBuffer2/simple2
2016-01-14 21:58:59,495 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 21:58:59,495 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 21:58:59,561 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.060
2016-01-14 21:58:59,563 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 21:58:59,564 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 21:58:59,591 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 21:58:59,591 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lower+upper.txt"]
2016-01-14 21:58:59,592 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer3/simple3"]
2016-01-14 21:58:59,592 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 21:58:59,592 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 21:58:59,592 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 21:58:59,593 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...testSimpleBuffer3/simple3
2016-01-14 21:58:59,653 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local_0006
2016-01-14 21:58:59,655 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 21:58:59,682 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lower+upper.txt
2016-01-14 21:58:59,700 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 21:58:59,700 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 21:58:59,710 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: FE972498A85648F99129395C5E8FA796, ordinal: 0
2016-01-14 21:58:59,712 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lower+upper.txt"]
2016-01-14 21:58:59,712 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:59,713 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FE972498A85648F99129395C5E8FA796, mem on start (mb), free: 45, total: 630, max: 672
2016-01-14 21:58:59,716 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FE972498A85648F99129395C5E8FA796, mem on close (mb), free: 45, total: 630, max: 672
2016-01-14 21:58:59,727 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 21:58:59,728 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 21:58:59,738 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: AC4952348C8147BA863B7D4AFFE763FF, ordinal: 1
2016-01-14 21:58:59,740 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:59,740 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer3/simple3"]
2016-01-14 21:58:59,742 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: AC4952348C8147BA863B7D4AFFE763FF, mem on start (mb), free: 43, total: 630, max: 672
2016-01-14 21:58:59,750 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: AC4952348C8147BA863B7D4AFFE763FF, mem on close (mb), free: 43, total: 630, max: 672
2016-01-14 21:58:59,758 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testSimpleBuffer3/simple3/_temporary
2016-01-14 21:58:59,760 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.167
2016-01-14 21:58:59,784 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output//hadoop/bufferpipesplatform/testSimpleBuffer3/simple3
2016-01-14 21:58:59,798 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 21:58:59,799 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 21:58:59,850 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.045
2016-01-14 21:58:59,853 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 21:58:59,853 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 21:58:59,872 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 21:58:59,872 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:59,872 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferReverse/firstn"]
2016-01-14 21:58:59,873 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 21:58:59,873 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 21:58:59,873 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 21:58:59,874 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...irstNBufferReverse/firstn
2016-01-14 21:58:59,904 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local_0007
2016-01-14 21:58:59,905 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 21:58:59,928 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt
2016-01-14 21:58:59,943 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 21:58:59,943 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 21:58:59,949 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: CC626D11C80B4E94A3ABDB050C808FD1, ordinal: 0
2016-01-14 21:58:59,950 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:58:59,950 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:59,950 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: CC626D11C80B4E94A3ABDB050C808FD1, mem on start (mb), free: 156, total: 742, max: 742
2016-01-14 21:58:59,953 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: CC626D11C80B4E94A3ABDB050C808FD1, mem on close (mb), free: 156, total: 742, max: 742
2016-01-14 21:58:59,962 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 21:58:59,963 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 21:58:59,970 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: BF42234CDFB0419A877EEA708565DA8D, ordinal: 1
2016-01-14 21:58:59,970 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:58:59,971 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferReverse/firstn"]
2016-01-14 21:58:59,971 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: BF42234CDFB0419A877EEA708565DA8D, mem on start (mb), free: 154, total: 742, max: 742
2016-01-14 21:58:59,975 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: BF42234CDFB0419A877EEA708565DA8D, mem on close (mb), free: 154, total: 742, max: 742
2016-01-14 21:58:59,984 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBufferReverse/firstn/_temporary
2016-01-14 21:58:59,984 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.111
2016-01-14 21:58:59,999 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output//hadoop/bufferpipesplatform/testFirstNBufferReverse/firstn
2016-01-14 21:59:00,010 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 21:59:00,010 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 21:59:00,032 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] executed rule registry: MapReduceHadoopRuleRegistry, completed as: ILLEGAL, in: 00:00.017
2016-01-14 21:59:00,032 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [lower*upper] rule registry: MapReduceHadoopRuleRegistry, found assembly to be malformed
2016-01-14 21:59:00,043 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-14 21:59:00,043 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 21:59:00,076 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.029
2016-01-14 21:59:00,077 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-01-14 21:59:00,078 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 21:59:00,093 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-14 21:59:00,093 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:59:00,094 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBuffer/firstn"]
2016-01-14 21:59:00,094 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-14 21:59:00,094 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 1
2016-01-14 21:59:00,095 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-14 21:59:00,096 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/1) ...m/testFirstNBuffer/firstn
2016-01-14 21:59:00,126 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local_0008
2016-01-14 21:59:00,128 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-14 21:59:00,131 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt
2016-01-14 21:59:00,230 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-14 21:59:00,230 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-14 21:59:00,236 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: E90A726274054A61BE3E7D52C0D3E1D1, ordinal: 0
2016-01-14 21:59:00,237 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/classes/test/data/lhs.txt"]
2016-01-14 21:59:00,237 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:59:00,238 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: E90A726274054A61BE3E7D52C0D3E1D1, mem on start (mb), free: 35, total: 742, max: 742
2016-01-14 21:59:00,241 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: E90A726274054A61BE3E7D52C0D3E1D1, mem on close (mb), free: 35, total: 742, max: 742
2016-01-14 21:59:00,264 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-14 21:59:00,264 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-14 21:59:00,271 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: A16C87967CA041E39E545AF9772E56F0, ordinal: 1
2016-01-14 21:59:00,271 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'num']]
2016-01-14 21:59:00,271 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextDelimited[['num', 'lower']]"]["/opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBuffer/firstn"]
2016-01-14 21:59:00,271 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A16C87967CA041E39E545AF9772E56F0, mem on start (mb), free: 244, total: 735, max: 735
2016-01-14 21:59:00,275 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A16C87967CA041E39E545AF9772E56F0, mem on close (mb), free: 243, total: 735, max: 735
2016-01-14 21:59:00,288 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output/hadoop/bufferpipesplatform/testFirstNBuffer/firstn/_temporary
2016-01-14 21:59:00,288 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:00.194
2016-01-14 21:59:00,304 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/d1e2e77517c04a30/apache-1.1.x/build/test/output//hadoop/bufferpipesplatform/testFirstNBuffer/firstn