2016-01-15 04:27:11,438 INFO platform.PlatformRunner (PlatformRunner.java:getPlatformClassesFromClasspath(195)) - found 1 test platforms from classpath
2016-01-15 04:27:11,459 INFO platform.PlatformRunner (PlatformRunner.java:addPlatform(244)) - adding test: cascading.UnmodifiablePipesPlatformTest, with platform: hadoop2-mr1
2016-01-15 04:27:11,484 INFO hadoop2.Hadoop2MR1Platform (Hadoop2MR1Platform.java:setUp(119)) - not using cluster
2016-01-15 04:27:12,304 WARN util.NativeCodeLoader (NativeCodeLoader.java:<clinit>(62)) - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
2016-01-15 04:27:12,506 INFO platform.TestPlatform (TestPlatform.java:getGlobalProperties(73)) - platform property overrides:
2016-01-15 04:27:12,635 INFO util.Util (Util.java:findMainClass(1327)) - using default application jar, may cause class not found exceptions on the cluster
2016-01-15 04:27:12,638 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(174)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.0.4/6099926be810c2c62f4bd24f369d52cdd84cf31d/cascading-hadoop2-mr1-3.0.4.jar
2016-01-15 04:27:12,718 INFO property.AppProps (AppProps.java:getAppID(177)) - using app.id: 59CEC6000F124CDBA0CF7F4D8D3D36DC
2016-01-15 04:27:12,964 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.185
2016-01-15 04:27:12,969 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 2, nodes: 4
2016-01-15 04:27:12,969 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-15 04:27:13,193 INFO util.Version (Version.java:printBanner(82)) - Concurrent, Inc - Cascading 3.0.4
2016-01-15 04:27:13,195 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting
2016-01-15 04:27:13,195 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] source: Hfs["TextDelimited[['lhs', 'rhs']]"]["/opt/buildAgent-8.0.3/work/aa7cf7440ab7c2d9/apache-2.7.x/build/classes/test/data/lhs.txt"]
2016-01-15 04:27:13,196 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] sink: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/aa7cf7440ab7c2d9/apache-2.7.x/build/test/output/hadoop2-mr1/unmodifiablepipesplatform/testUnmodifiable/simple"]
2016-01-15 04:27:13,196 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] parallel execution of steps is enabled: false
2016-01-15 04:27:13,197 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] executing total steps: 2
2016-01-15 04:27:13,198 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] allocating management threads: 1
2016-01-15 04:27:13,199 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (1/2)
2016-01-15 04:27:13,954 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local272948300_0001
2016-01-15 04:27:13,954 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-15 04:27:14,035 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/aa7cf7440ab7c2d9/apache-2.7.x/build/classes/test/data/lhs.txt
2016-01-15 04:27:14,175 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-15 04:27:14,175 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-15 04:27:14,187 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: EE3DD31E4A1A46CD887043700D2FFE53, ordinal: 0
2016-01-15 04:27:14,221 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextDelimited[['lhs', 'rhs']]"]["/opt/buildAgent-8.0.3/work/aa7cf7440ab7c2d9/apache-2.7.x/build/classes/test/data/lhs.txt"]
2016-01-15 04:27:14,221 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'lhs']]
2016-01-15 04:27:14,222 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EE3DD31E4A1A46CD887043700D2FFE53, mem on start (mb), free: 114, total: 281, max: 672
2016-01-15 04:27:14,232 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EE3DD31E4A1A46CD887043700D2FFE53, mem on close (mb), free: 113, total: 281, max: 672
2016-01-15 04:27:14,314 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-15 04:27:14,314 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-15 04:27:14,326 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: BCEA1F4F84B54895A3F4516383FC1466, ordinal: 1
2016-01-15 04:27:14,331 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'lhs']]
2016-01-15 04:27:14,332 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: TempHfs["SequenceFile[['lhs', 'rhs']]"][3681730765/test/]
2016-01-15 04:27:14,332 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: BCEA1F4F84B54895A3F4516383FC1466, mem on start (mb), free: 102, total: 281, max: 672
2016-01-15 04:27:14,366 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: BCEA1F4F84B54895A3F4516383FC1466, mem on close (mb), free: 99, total: 281, max: 672
2016-01-15 04:27:14,410 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] starting step: (2/2) ...m/testUnmodifiable/simple
2016-01-15 04:27:14,608 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] submitted hadoop job: job_local32379995_0002
2016-01-15 04:27:14,609 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] tracking url: http://localhost:8080/
2016-01-15 04:27:14,616 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/tmp/hadoop-teamcity/3681730765_test_758CDCCCF1D74D3481E23441BC11CBBD/part-00000
2016-01-15 04:27:14,750 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.0.4
2016-01-15 04:27:14,751 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-01-15 04:27:14,769 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: DC2B7864751A4A06A78DC8747F76EB94, ordinal: 0
2016-01-15 04:27:14,769 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: TempHfs["SequenceFile[['lhs', 'rhs']]"][3681730765/test/]
2016-01-15 04:27:14,770 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'lhs']]
2016-01-15 04:27:14,770 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: DC2B7864751A4A06A78DC8747F76EB94, mem on start (mb), free: 168, total: 382, max: 672
2016-01-15 04:27:14,772 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: DC2B7864751A4A06A78DC8747F76EB94, mem on close (mb), free: 168, total: 382, max: 672
2016-01-15 04:27:14,789 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.0.4
2016-01-15 04:27:14,789 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-01-15 04:27:14,802 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: C848E5FD9FB34AAABDFDEA874D4243DF, ordinal: 1
2016-01-15 04:27:14,805 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'lhs']]
2016-01-15 04:27:14,805 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/aa7cf7440ab7c2d9/apache-2.7.x/build/test/output/hadoop2-mr1/unmodifiablepipesplatform/testUnmodifiable/simple"]
2016-01-15 04:27:14,807 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: C848E5FD9FB34AAABDFDEA874D4243DF, mem on start (mb), free: 164, total: 382, max: 672
2016-01-15 04:27:14,821 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: C848E5FD9FB34AAABDFDEA874D4243DF, mem on close (mb), free: 163, total: 382, max: 672
2016-01-15 04:27:14,854 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/aa7cf7440ab7c2d9/apache-2.7.x/build/test/output/hadoop2-mr1/unmodifiablepipesplatform/testUnmodifiable/simple/_temporary
2016-01-15 04:27:14,855 INFO flow.Flow (BaseFlow.java:logInfo(1433)) - [test] completed in: 00:01.656
2016-01-15 04:27:14,902 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/aa7cf7440ab7c2d9/apache-2.7.x/build/test/output//hadoop2-mr1/unmodifiablepipesplatform/testUnmodifiable/simple