2015-08-14 00:35:55,470 INFO platform.PlatformRunner (PlatformRunner.java:getPlatformClassesFromClasspath(187)) - found 1 test platforms from classpath
2015-08-14 00:35:55,521 INFO platform.PlatformRunner (PlatformRunner.java:addPlatform(237)) - installing platform: hadoop
2015-08-14 00:35:55,521 INFO platform.PlatformRunner (PlatformRunner.java:addPlatform(238)) - running test: cascading.function.FunctionPlatformTest
2015-08-14 00:35:55,555 INFO hadoop.HadoopPlatform (HadoopPlatform.java:setUp(112)) - not using cluster
2015-08-14 00:35:55,858 INFO platform.TestPlatform (TestPlatform.java:getGlobalProperties(72)) - platform property overrides:
2015-08-14 00:35:55,917 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(105)) - flushed keys num times: 1, with capacity: 2
2015-08-14 00:35:55,917 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(106)) - mem on flush (mb), free: 94, total: 102, max: 672
2015-08-14 00:35:55,918 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(111)) - total mem is 15% of max mem, to better utilize unused memory consider increasing the cache size
2015-08-14 00:35:55,919 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(105)) - flushed keys num times: 3, with capacity: 2
2015-08-14 00:35:55,919 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(106)) - mem on flush (mb), free: 94, total: 102, max: 672
2015-08-14 00:35:55,920 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(111)) - total mem is 15% of max mem, to better utilize unused memory consider increasing the cache size
2015-08-14 00:35:56,018 INFO util.HadoopUtil (HadoopUtil.java:findMainClass(336)) - using default application jar, may cause class not found exceptions on the cluster
2015-08-14 00:35:56,021 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(226)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/2.7.1/e28a477e50cd8946adea60fa2bb90b57eaf07ef3/cascading-hadoop-2.7.1.jar
2015-08-14 00:35:56,034 INFO property.AppProps (AppProps.java:getAppID(169)) - using app.id: 0EC203BD07314951B99B08E62440FE08
2015-08-14 00:35:56,242 INFO util.Version (Version.java:printBanner(85)) - Concurrent, Inc - Cascading 2.7.1
2015-08-14 00:35:56,243 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [formatter] starting
2015-08-14 00:35:56,245 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [formatter] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/classes/test/data/upper.txt"]
2015-08-14 00:35:56,246 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [formatter] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output/hadoop/functionplatform/formatter"]
2015-08-14 00:35:56,246 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [formatter] parallel execution is enabled: false
2015-08-14 00:35:56,246 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [formatter] starting jobs: 1
2015-08-14 00:35:56,247 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [formatter] allocating threads: 1
2015-08-14 00:35:56,248 INFO flow.FlowStep (BaseFlowStep.java:logInfo(895)) - [formatter] starting step: (1/1) ...unctionplatform/formatter
2015-08-14 00:35:56,297 WARN util.NativeCodeLoader (NativeCodeLoader.java:<clinit>(52)) - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
2015-08-14 00:35:56,368 WARN snappy.LoadSnappy (LoadSnappy.java:<clinit>(46)) - Snappy native library not loaded
2015-08-14 00:35:56,606 INFO flow.FlowStep (BaseFlowStep.java:logInfo(895)) - [formatter] submitted hadoop job: job_local1073718467_0001
2015-08-14 00:35:56,607 INFO flow.FlowStep (BaseFlowStep.java:logInfo(895)) - [formatter] tracking url: http://localhost:8080/
2015-08-14 00:35:56,724 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/classes/test/data/upper.txt
2015-08-14 00:35:56,742 INFO hadoop.FlowMapper (FlowMapper.java:configure(71)) - cascading version: 2.7.1
2015-08-14 00:35:56,742 INFO hadoop.FlowMapper (FlowMapper.java:configure(72)) - child jvm opts: -Xmx200m
2015-08-14 00:35:56,786 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/classes/test/data/upper.txt"]
2015-08-14 00:35:56,787 INFO hadoop.FlowMapper (FlowMapper.java:configure(90)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output/hadoop/functionplatform/formatter"]
2015-08-14 00:35:56,830 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(219)) - deleting temp path /opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output/hadoop/functionplatform/formatter/_temporary
2015-08-14 00:35:56,885 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(209)) - copying to local /opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output//hadoop/functionplatform/formatter
2015-08-14 00:35:56,888 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(105)) - flushed keys num times: 1, with capacity: 2
2015-08-14 00:35:56,889 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(106)) - mem on flush (mb), free: 93, total: 102, max: 672
2015-08-14 00:35:56,889 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(111)) - total mem is 15% of max mem, to better utilize unused memory consider increasing the cache size
2015-08-14 00:35:56,890 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(105)) - flushed keys num times: 3, with capacity: 2
2015-08-14 00:35:56,890 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(106)) - mem on flush (mb), free: 93, total: 102, max: 672
2015-08-14 00:35:56,890 INFO cache.LRUHashMapCache (LRUHashMapCache.java:removeEldestEntry(111)) - total mem is 15% of max mem, to better utilize unused memory consider increasing the cache size
2015-08-14 00:35:56,922 INFO util.HadoopUtil (HadoopUtil.java:findMainClass(336)) - using default application jar, may cause class not found exceptions on the cluster
2015-08-14 00:35:56,922 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(226)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/2.7.1/e28a477e50cd8946adea60fa2bb90b57eaf07ef3/cascading-hadoop-2.7.1.jar
2015-08-14 00:35:56,963 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [apache] starting
2015-08-14 00:35:56,964 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [apache] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/classes/test/data/apache.200.txt"]
2015-08-14 00:35:56,964 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [apache] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output/hadoop/functionplatform/insert"]
2015-08-14 00:35:56,964 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [apache] parallel execution is enabled: false
2015-08-14 00:35:56,965 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [apache] starting jobs: 1
2015-08-14 00:35:56,965 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [apache] allocating threads: 1
2015-08-14 00:35:56,965 INFO flow.FlowStep (BaseFlowStep.java:logInfo(895)) - [apache] starting step: (1/1) ...p/functionplatform/insert
2015-08-14 00:35:57,084 INFO flow.FlowStep (BaseFlowStep.java:logInfo(895)) - [apache] submitted hadoop job: job_local1970038922_0002
2015-08-14 00:35:57,085 INFO flow.FlowStep (BaseFlowStep.java:logInfo(895)) - [apache] tracking url: http://localhost:8080/
2015-08-14 00:35:57,092 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/classes/test/data/apache.200.txt
2015-08-14 00:35:57,263 INFO hadoop.FlowMapper (FlowMapper.java:configure(71)) - cascading version: 2.7.1
2015-08-14 00:35:57,264 INFO hadoop.FlowMapper (FlowMapper.java:configure(72)) - child jvm opts: -Xmx200m
2015-08-14 00:35:57,285 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/classes/test/data/apache.200.txt"]
2015-08-14 00:35:57,285 INFO hadoop.FlowMapper (FlowMapper.java:configure(90)) - sinking to: GroupBy(apache)[by:[{1}:'A']]
2015-08-14 00:35:57,382 INFO hadoop.FlowReducer (FlowReducer.java:configure(78)) - cascading version: 2.7.1
2015-08-14 00:35:57,382 INFO hadoop.FlowReducer (FlowReducer.java:configure(79)) - child jvm opts: -Xmx200m
2015-08-14 00:35:57,400 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - sourcing from: GroupBy(apache)[by:[{1}:'A']]
2015-08-14 00:35:57,400 INFO hadoop.FlowReducer (FlowReducer.java:configure(100)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output/hadoop/functionplatform/insert"]
2015-08-14 00:35:57,460 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(219)) - deleting temp path /opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output/hadoop/functionplatform/insert/_temporary
2015-08-14 00:35:57,522 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(209)) - copying to local /opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output//hadoop/functionplatform/insert
2015-08-14 00:35:57,549 INFO util.HadoopUtil (HadoopUtil.java:findMainClass(336)) - using default application jar, may cause class not found exceptions on the cluster
2015-08-14 00:35:57,550 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(226)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/2.7.1/e28a477e50cd8946adea60fa2bb90b57eaf07ef3/cascading-hadoop-2.7.1.jar
2015-08-14 00:35:57,586 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [setvalue] starting
2015-08-14 00:35:57,586 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [setvalue] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/classes/test/data/upper.txt"]
2015-08-14 00:35:57,586 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [setvalue] sink: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output/hadoop/functionplatform/setvalue"]
2015-08-14 00:35:57,587 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [setvalue] parallel execution is enabled: false
2015-08-14 00:35:57,587 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [setvalue] starting jobs: 1
2015-08-14 00:35:57,587 INFO flow.Flow (BaseFlow.java:logInfo(1370)) - [setvalue] allocating threads: 1
2015-08-14 00:35:57,588 INFO flow.FlowStep (BaseFlowStep.java:logInfo(895)) - [setvalue] starting step: (1/1) ...functionplatform/setvalue
2015-08-14 00:35:57,653 INFO flow.FlowStep (BaseFlowStep.java:logInfo(895)) - [setvalue] submitted hadoop job: job_local732476109_0003
2015-08-14 00:35:57,653 INFO flow.FlowStep (BaseFlowStep.java:logInfo(895)) - [setvalue] tracking url: http://localhost:8080/
2015-08-14 00:35:57,660 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/classes/test/data/upper.txt
2015-08-14 00:35:57,667 INFO hadoop.FlowMapper (FlowMapper.java:configure(71)) - cascading version: 2.7.1
2015-08-14 00:35:57,667 INFO hadoop.FlowMapper (FlowMapper.java:configure(72)) - child jvm opts: -Xmx200m
2015-08-14 00:35:57,679 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/classes/test/data/upper.txt"]
2015-08-14 00:35:57,679 INFO hadoop.FlowMapper (FlowMapper.java:configure(90)) - sinking to: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output/hadoop/functionplatform/setvalue"]
2015-08-14 00:35:57,695 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(219)) - deleting temp path /opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output/hadoop/functionplatform/setvalue/_temporary
2015-08-14 00:35:57,723 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(209)) - copying to local /opt/buildAgent-8.0.3/work/8c57748d0461e126/apache-1.2.x/build/test/output//hadoop/functionplatform/setvalue