Class cascading.SortedValuesPlatformTest

2016-05-17 22:01:17,657 INFO  platform.PlatformRunner (PlatformRunner.java:getPlatformClassesFromClasspath(197)) - found 1 test platforms from classpath
2016-05-17 22:01:17,688 INFO  platform.PlatformRunner (PlatformRunner.java:addPlatform(246)) - adding test: cascading.SortedValuesPlatformTest, with platform: hadoop2-mr1
2016-05-17 22:01:17,725 INFO  hadoop2.Hadoop2MR1Platform (Hadoop2MR1Platform.java:setUp(119)) - not using cluster
2016-05-17 22:01:18,325 WARN  util.NativeCodeLoader (NativeCodeLoader.java:<clinit>(62)) - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
2016-05-17 22:01:18,480 INFO  platform.TestPlatform (TestPlatform.java:getGlobalProperties(75)) - platform property overrides: 
2016-05-17 22:01:18,598 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:18,600 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:18,711 INFO  property.AppProps (AppProps.java:getAppID(177)) - using app.id: 8050AA07F2B640EF8CBA58D7C79D34E0
2016-05-17 22:01:18,958 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.191
2016-05-17 22:01:18,962 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:18,962 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:19,160 INFO  util.Version (Version.java:printBanner(82)) - Concurrent, Inc - Cascading 3.1.0
2016-05-17 22:01:19,171 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] starting
2016-05-17 22:01:19,172 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/apache.200.txt"]
2016-05-17 22:01:19,174 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/ips.20.txt"]
2016-05-17 22:01:19,176 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  sink: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testCoGroupComparatorValuesReversed/cogroupcomparereversed"]
2016-05-17 22:01:19,179 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  parallel execution of steps is enabled: false
2016-05-17 22:01:19,180 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  executing total steps: 1
2016-05-17 22:01:19,181 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  allocating management threads: 1
2016-05-17 22:01:19,183 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] starting step: (1/1) ...ed/cogroupcomparereversed
2016-05-17 22:01:19,658 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1628705261/.staging/job_local1628705261_0001/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:19,670 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1628705261/.staging/job_local1628705261_0001/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:19,818 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1628705261_0001/job_local1628705261_0001.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:19,818 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1628705261_0001/job_local1628705261_0001.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:19,830 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] submitted hadoop job: job_local1628705261_0001
2016-05-17 22:01:19,830 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] tracking url: http://localhost:8080/
2016-05-17 22:01:19,929 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/apache.200.txt
2016-05-17 22:01:20,115 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:20,116 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:20,145 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: D5CCA0629C1F40939D60B469D77A64B4, ordinal: 0
2016-05-17 22:01:20,194 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/apache.200.txt"]
2016-05-17 22:01:20,195 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: CoGroup(apache*ip)[by: apache:[{1}:'octet'] ip:[{1}:'rawoctet']]
2016-05-17 22:01:20,196 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: D5CCA0629C1F40939D60B469D77A64B4, mem on start (mb), free: 89, total: 223, max: 672
2016-05-17 22:01:20,248 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: D5CCA0629C1F40939D60B469D77A64B4, mem on close (mb), free: 88, total: 223, max: 672
2016-05-17 22:01:20,287 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/ips.20.txt
2016-05-17 22:01:20,389 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:20,389 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:20,409 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: D5CCA0629C1F40939D60B469D77A64B4, ordinal: 0
2016-05-17 22:01:20,410 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/ips.20.txt"]
2016-05-17 22:01:20,410 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: CoGroup(apache*ip)[by: apache:[{1}:'octet'] ip:[{1}:'rawoctet']]
2016-05-17 22:01:20,411 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: D5CCA0629C1F40939D60B469D77A64B4, mem on start (mb), free: 86, total: 323, max: 672
2016-05-17 22:01:20,415 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: D5CCA0629C1F40939D60B469D77A64B4, mem on close (mb), free: 86, total: 323, max: 672
2016-05-17 22:01:20,456 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:20,456 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:20,468 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: B33549E63CB545619CA4414B95444BB3, ordinal: 1
2016-05-17 22:01:20,471 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: CoGroup(apache*ip)[by: apache:[{1}:'octet'] ip:[{1}:'rawoctet']]
2016-05-17 22:01:20,471 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testCoGroupComparatorValuesReversed/cogroupcomparereversed"]
2016-05-17 22:01:20,472 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B33549E63CB545619CA4414B95444BB3, mem on start (mb), free: 84, total: 323, max: 672
2016-05-17 22:01:20,491 INFO  collect.SpillableTupleList (SpillableTupleList.java:getCodecClass(89)) - attempting to load codec: org.apache.hadoop.io.compress.GzipCodec
2016-05-17 22:01:20,491 INFO  collect.SpillableTupleList (SpillableTupleList.java:getCodecClass(94)) - found codec: org.apache.hadoop.io.compress.GzipCodec
2016-05-17 22:01:20,529 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B33549E63CB545619CA4414B95444BB3, mem on close (mb), free: 82, total: 323, max: 672
2016-05-17 22:01:20,564 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testCoGroupComparatorValuesReversed/cogroupcomparereversed/_temporary
2016-05-17 22:01:20,565 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  completed in: 00:01.392
2016-05-17 22:01:20,615 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testCoGroupComparatorValuesReversed/cogroupcomparereversed
2016-05-17 22:01:20,641 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:20,642 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:20,732 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.081
2016-05-17 22:01:20,734 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:20,734 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:20,766 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] starting
2016-05-17 22:01:20,767 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/apache.200.txt"]
2016-05-17 22:01:20,767 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/ips.20.txt"]
2016-05-17 22:01:20,768 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  sink: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testCoGroupComparatorValues/cogroupcompareforward"]
2016-05-17 22:01:20,768 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  parallel execution of steps is enabled: false
2016-05-17 22:01:20,768 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  executing total steps: 1
2016-05-17 22:01:20,769 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  allocating management threads: 1
2016-05-17 22:01:20,770 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] starting step: (1/1) ...ues/cogroupcompareforward
2016-05-17 22:01:20,866 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1640236322/.staging/job_local1640236322_0002/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:20,870 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1640236322/.staging/job_local1640236322_0002/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:20,922 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1640236322_0002/job_local1640236322_0002.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:20,922 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1640236322_0002/job_local1640236322_0002.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:20,924 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] submitted hadoop job: job_local1640236322_0002
2016-05-17 22:01:20,924 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip] tracking url: http://localhost:8080/
2016-05-17 22:01:20,933 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/apache.200.txt
2016-05-17 22:01:21,034 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:21,034 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:21,049 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: C3240E8C72844519B0697FB911D52702, ordinal: 0
2016-05-17 22:01:21,050 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/apache.200.txt"]
2016-05-17 22:01:21,050 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: CoGroup(apache*ip)[by: apache:[{1}:'octet'] ip:[{1}:'rawoctet']]
2016-05-17 22:01:21,051 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C3240E8C72844519B0697FB911D52702, mem on start (mb), free: 98, total: 424, max: 672
2016-05-17 22:01:21,087 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C3240E8C72844519B0697FB911D52702, mem on close (mb), free: 97, total: 424, max: 672
2016-05-17 22:01:21,113 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/ips.20.txt
2016-05-17 22:01:21,213 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:21,213 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:21,229 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: C3240E8C72844519B0697FB911D52702, ordinal: 0
2016-05-17 22:01:21,230 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/ips.20.txt"]
2016-05-17 22:01:21,230 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: CoGroup(apache*ip)[by: apache:[{1}:'octet'] ip:[{1}:'rawoctet']]
2016-05-17 22:01:21,230 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C3240E8C72844519B0697FB911D52702, mem on start (mb), free: 96, total: 524, max: 672
2016-05-17 22:01:21,234 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C3240E8C72844519B0697FB911D52702, mem on close (mb), free: 96, total: 524, max: 672
2016-05-17 22:01:21,254 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:21,254 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:21,264 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: ECB087E350E04272B487AD5A364609E9, ordinal: 1
2016-05-17 22:01:21,265 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: CoGroup(apache*ip)[by: apache:[{1}:'octet'] ip:[{1}:'rawoctet']]
2016-05-17 22:01:21,265 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testCoGroupComparatorValues/cogroupcompareforward"]
2016-05-17 22:01:21,266 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: ECB087E350E04272B487AD5A364609E9, mem on start (mb), free: 94, total: 524, max: 672
2016-05-17 22:01:21,291 INFO  collect.SpillableTupleList (SpillableTupleList.java:getCodecClass(89)) - attempting to load codec: org.apache.hadoop.io.compress.GzipCodec
2016-05-17 22:01:21,291 INFO  collect.SpillableTupleList (SpillableTupleList.java:getCodecClass(94)) - found codec: org.apache.hadoop.io.compress.GzipCodec
2016-05-17 22:01:21,319 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: ECB087E350E04272B487AD5A364609E9, mem on close (mb), free: 93, total: 524, max: 672
2016-05-17 22:01:21,358 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testCoGroupComparatorValues/cogroupcompareforward/_temporary
2016-05-17 22:01:21,358 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache*ip]  completed in: 00:00.591
2016-05-17 22:01:21,404 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testCoGroupComparatorValues/cogroupcompareforward
2016-05-17 22:01:21,425 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:21,426 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:21,464 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache] executed rule registry: MapReduceHadoopRuleRegistry, completed as: ILLEGAL, in: 00:00.030
2016-05-17 22:01:21,465 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [apache] rule registry: MapReduceHadoopRuleRegistry, found assembly to be malformed
2016-05-17 22:01:21,490 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:21,491 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:21,562 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.062
2016-05-17 22:01:21,564 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:21,564 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:21,593 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:21,594 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:21,594 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_false_false"]
2016-05-17 22:01:21,599 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:21,599 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:21,599 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:21,601 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ve/false_null_false_false
2016-05-17 22:01:21,800 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1844541701/.staging/job_local1844541701_0003/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:21,802 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1844541701/.staging/job_local1844541701_0003/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:21,870 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1844541701_0003/job_local1844541701_0003.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:21,870 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1844541701_0003/job_local1844541701_0003.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:21,872 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local1844541701_0003
2016-05-17 22:01:21,873 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:21,881 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:21,925 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:21,926 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:21,936 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: F17688D14EF4484C917E121E784C7CBD, ordinal: 0
2016-05-17 22:01:21,937 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:21,937 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:21,938 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: F17688D14EF4484C917E121E784C7CBD, mem on start (mb), free: 65, total: 583, max: 672
2016-05-17 22:01:21,944 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: F17688D14EF4484C917E121E784C7CBD, mem on close (mb), free: 65, total: 583, max: 672
2016-05-17 22:01:21,962 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:21,963 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:21,971 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: AD5B9A5367EE48F5B01481EA928516FE, ordinal: 1
2016-05-17 22:01:21,971 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:21,971 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_false_false"]
2016-05-17 22:01:21,971 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: AD5B9A5367EE48F5B01481EA928516FE, mem on start (mb), free: 64, total: 583, max: 672
2016-05-17 22:01:21,986 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: AD5B9A5367EE48F5B01481EA928516FE, mem on close (mb), free: 63, total: 583, max: 672
2016-05-17 22:01:22,014 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_false_false/_temporary
2016-05-17 22:01:22,014 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.420
2016-05-17 22:01:22,074 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:22,075 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:22,119 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.039
2016-05-17 22:01:22,120 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:22,121 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:22,139 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:22,139 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:22,140 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_false_false"]
2016-05-17 22:01:22,140 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:22,140 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:22,140 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:22,141 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ive/true_null_false_false
2016-05-17 22:01:22,208 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1275416807/.staging/job_local1275416807_0004/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:22,211 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1275416807/.staging/job_local1275416807_0004/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:22,249 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1275416807_0004/job_local1275416807_0004.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:22,249 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1275416807_0004/job_local1275416807_0004.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:22,251 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local1275416807_0004
2016-05-17 22:01:22,253 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:22,260 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:22,359 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:22,359 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:22,365 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: A6C4E86894E44E11B12930BF38CED377, ordinal: 0
2016-05-17 22:01:22,366 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:22,366 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:22,367 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A6C4E86894E44E11B12930BF38CED377, mem on start (mb), free: 84, total: 630, max: 672
2016-05-17 22:01:22,372 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A6C4E86894E44E11B12930BF38CED377, mem on close (mb), free: 84, total: 630, max: 672
2016-05-17 22:01:22,386 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:22,386 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:22,393 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: B9443F6F2DCF4F2FB596940EE67C2EB2, ordinal: 1
2016-05-17 22:01:22,394 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:22,394 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_false_false"]
2016-05-17 22:01:22,394 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B9443F6F2DCF4F2FB596940EE67C2EB2, mem on start (mb), free: 83, total: 630, max: 672
2016-05-17 22:01:22,406 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B9443F6F2DCF4F2FB596940EE67C2EB2, mem on close (mb), free: 82, total: 630, max: 672
2016-05-17 22:01:22,424 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_false_false/_temporary
2016-05-17 22:01:22,424 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.284
2016-05-17 22:01:22,465 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:22,465 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:22,508 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.039
2016-05-17 22:01:22,509 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:22,509 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:22,525 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:22,525 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:22,526 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_true_false"]
2016-05-17 22:01:22,526 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:22,526 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:22,527 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:22,527 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ive/false_null_true_false
2016-05-17 22:01:22,609 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity999361914/.staging/job_local999361914_0005/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:22,611 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity999361914/.staging/job_local999361914_0005/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:22,646 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local999361914_0005/job_local999361914_0005.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:22,647 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local999361914_0005/job_local999361914_0005.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:22,648 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local999361914_0005
2016-05-17 22:01:22,648 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:22,654 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:22,868 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:22,868 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:22,874 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: A61968DB54304D4E8CCA9C9FB859100A, ordinal: 0
2016-05-17 22:01:22,875 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:22,875 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:22,876 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A61968DB54304D4E8CCA9C9FB859100A, mem on start (mb), free: 126, total: 235, max: 672
2016-05-17 22:01:22,879 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A61968DB54304D4E8CCA9C9FB859100A, mem on close (mb), free: 126, total: 235, max: 672
2016-05-17 22:01:22,895 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:22,896 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:22,901 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: F00CAA7874184EB3A9965410ED0542A1, ordinal: 1
2016-05-17 22:01:22,902 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:22,902 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_true_false"]
2016-05-17 22:01:22,902 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F00CAA7874184EB3A9965410ED0542A1, mem on start (mb), free: 124, total: 235, max: 672
2016-05-17 22:01:22,917 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F00CAA7874184EB3A9965410ED0542A1, mem on close (mb), free: 123, total: 235, max: 672
2016-05-17 22:01:22,941 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_true_false/_temporary
2016-05-17 22:01:22,941 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.416
2016-05-17 22:01:22,980 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:22,980 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:23,115 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.131
2016-05-17 22:01:23,116 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:23,116 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:23,138 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:23,138 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:23,139 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_true_false"]
2016-05-17 22:01:23,139 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:23,139 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:23,139 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:23,140 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...sive/true_null_true_false
2016-05-17 22:01:23,199 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity944704084/.staging/job_local944704084_0006/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:23,201 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity944704084/.staging/job_local944704084_0006/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:23,238 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local944704084_0006/job_local944704084_0006.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:23,238 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local944704084_0006/job_local944704084_0006.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:23,240 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local944704084_0006
2016-05-17 22:01:23,243 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:23,250 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:23,271 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:23,272 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:23,279 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: FECE33CC29DC4CBDA3FB0561690CFE2D, ordinal: 0
2016-05-17 22:01:23,280 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:23,281 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:23,281 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FECE33CC29DC4CBDA3FB0561690CFE2D, mem on start (mb), free: 277, total: 419, max: 672
2016-05-17 22:01:23,286 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FECE33CC29DC4CBDA3FB0561690CFE2D, mem on close (mb), free: 277, total: 419, max: 672
2016-05-17 22:01:23,303 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:23,303 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:23,310 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: F466FDAB94494294B59C38B39F430158, ordinal: 1
2016-05-17 22:01:23,311 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:23,311 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_true_false"]
2016-05-17 22:01:23,311 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F466FDAB94494294B59C38B39F430158, mem on start (mb), free: 275, total: 419, max: 672
2016-05-17 22:01:23,325 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F466FDAB94494294B59C38B39F430158, mem on close (mb), free: 273, total: 419, max: 672
2016-05-17 22:01:23,343 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_true_false/_temporary
2016-05-17 22:01:23,343 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.204
2016-05-17 22:01:23,394 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:23,395 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:23,440 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.040
2016-05-17 22:01:23,443 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:23,443 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:23,468 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:23,469 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:23,469 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_false_false"]
2016-05-17 22:01:23,469 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:23,470 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:23,470 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:23,471 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...e/false_false_false_false
2016-05-17 22:01:23,569 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity241209663/.staging/job_local241209663_0007/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:23,570 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity241209663/.staging/job_local241209663_0007/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:23,625 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local241209663_0007/job_local241209663_0007.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:23,626 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local241209663_0007/job_local241209663_0007.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:23,628 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local241209663_0007
2016-05-17 22:01:23,628 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:23,635 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:23,650 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:23,651 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:23,657 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: BB80AE561C2D473A8962CB6A4E4C95C9, ordinal: 0
2016-05-17 22:01:23,659 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:23,659 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:23,660 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: BB80AE561C2D473A8962CB6A4E4C95C9, mem on start (mb), free: 124, total: 419, max: 672
2016-05-17 22:01:23,665 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: BB80AE561C2D473A8962CB6A4E4C95C9, mem on close (mb), free: 124, total: 419, max: 672
2016-05-17 22:01:23,680 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:23,680 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:23,691 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: E93EECF2D6C8404B8FB6F3A1CCCDBDBB, ordinal: 1
2016-05-17 22:01:23,692 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:23,693 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_false_false"]
2016-05-17 22:01:23,694 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: E93EECF2D6C8404B8FB6F3A1CCCDBDBB, mem on start (mb), free: 122, total: 419, max: 672
2016-05-17 22:01:23,719 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: E93EECF2D6C8404B8FB6F3A1CCCDBDBB, mem on close (mb), free: 344, total: 457, max: 672
2016-05-17 22:01:23,741 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_false_false/_temporary
2016-05-17 22:01:23,741 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.272
2016-05-17 22:01:23,784 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:23,785 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:23,822 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.032
2016-05-17 22:01:23,823 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:23,823 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:23,843 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:23,843 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:23,843 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_false_false"]
2016-05-17 22:01:23,844 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:23,844 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:23,844 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:23,846 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ve/true_false_false_false
2016-05-17 22:01:23,905 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1914288320/.staging/job_local1914288320_0008/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:23,907 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1914288320/.staging/job_local1914288320_0008/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:23,949 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1914288320_0008/job_local1914288320_0008.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:23,949 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1914288320_0008/job_local1914288320_0008.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:23,950 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local1914288320_0008
2016-05-17 22:01:23,950 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:23,956 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:23,976 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:23,976 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:23,984 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: FF67CEAB14874D64955036DB6378AA46, ordinal: 0
2016-05-17 22:01:23,985 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:23,985 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:23,985 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FF67CEAB14874D64955036DB6378AA46, mem on start (mb), free: 195, total: 457, max: 672
2016-05-17 22:01:23,990 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FF67CEAB14874D64955036DB6378AA46, mem on close (mb), free: 195, total: 457, max: 672
2016-05-17 22:01:24,004 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:24,004 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:24,010 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: C31A056DD44E4D1BA82FBAE46EFD41DE, ordinal: 1
2016-05-17 22:01:24,010 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:24,011 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_false_false"]
2016-05-17 22:01:24,011 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: C31A056DD44E4D1BA82FBAE46EFD41DE, mem on start (mb), free: 193, total: 457, max: 672
2016-05-17 22:01:24,024 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: C31A056DD44E4D1BA82FBAE46EFD41DE, mem on close (mb), free: 191, total: 457, max: 672
2016-05-17 22:01:24,048 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_false_false/_temporary
2016-05-17 22:01:24,049 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.205
2016-05-17 22:01:24,095 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:24,095 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:24,132 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.031
2016-05-17 22:01:24,133 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:24,133 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:24,148 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:24,148 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:24,148 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_false_false"]
2016-05-17 22:01:24,149 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:24,149 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:24,150 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:24,151 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ive/true_true_false_false
2016-05-17 22:01:24,221 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1419559401/.staging/job_local1419559401_0009/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:24,222 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1419559401/.staging/job_local1419559401_0009/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:24,268 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1419559401_0009/job_local1419559401_0009.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:24,269 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1419559401_0009/job_local1419559401_0009.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:24,270 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local1419559401_0009
2016-05-17 22:01:24,271 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:24,276 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:24,298 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:24,299 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:24,308 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: D9888A9A1DC54846925D4F554A9E8786, ordinal: 0
2016-05-17 22:01:24,309 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:24,309 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:24,309 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: D9888A9A1DC54846925D4F554A9E8786, mem on start (mb), free: 255, total: 467, max: 672
2016-05-17 22:01:24,314 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: D9888A9A1DC54846925D4F554A9E8786, mem on close (mb), free: 255, total: 467, max: 672
2016-05-17 22:01:24,330 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:24,330 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:24,335 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: B15E46791E974E598B40D0D64E0468C6, ordinal: 1
2016-05-17 22:01:24,335 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:24,336 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_false_false"]
2016-05-17 22:01:24,336 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B15E46791E974E598B40D0D64E0468C6, mem on start (mb), free: 252, total: 467, max: 672
2016-05-17 22:01:24,348 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B15E46791E974E598B40D0D64E0468C6, mem on close (mb), free: 251, total: 467, max: 672
2016-05-17 22:01:24,370 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_false_false/_temporary
2016-05-17 22:01:24,370 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.222
2016-05-17 22:01:24,405 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:24,406 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:24,436 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.027
2016-05-17 22:01:24,436 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:24,436 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:24,454 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:24,454 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:24,454 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_false_false"]
2016-05-17 22:01:24,455 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:24,455 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:24,456 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:24,456 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ve/false_true_false_false
2016-05-17 22:01:24,523 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1062952993/.staging/job_local1062952993_0010/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:24,525 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1062952993/.staging/job_local1062952993_0010/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:24,566 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1062952993_0010/job_local1062952993_0010.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:24,566 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1062952993_0010/job_local1062952993_0010.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:24,569 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local1062952993_0010
2016-05-17 22:01:24,569 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:24,575 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:24,597 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:24,597 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:24,604 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: A8308E9565B0438F84648193CB4DA1DA, ordinal: 0
2016-05-17 22:01:24,604 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:24,604 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:24,605 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A8308E9565B0438F84648193CB4DA1DA, mem on start (mb), free: 256, total: 467, max: 672
2016-05-17 22:01:24,609 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A8308E9565B0438F84648193CB4DA1DA, mem on close (mb), free: 256, total: 467, max: 672
2016-05-17 22:01:24,623 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:24,623 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:24,628 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: CE3C798AFA4045069468BD7C172984C6, ordinal: 1
2016-05-17 22:01:24,629 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:24,629 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_false_false"]
2016-05-17 22:01:24,629 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: CE3C798AFA4045069468BD7C172984C6, mem on start (mb), free: 254, total: 467, max: 672
2016-05-17 22:01:24,641 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: CE3C798AFA4045069468BD7C172984C6, mem on close (mb), free: 253, total: 467, max: 672
2016-05-17 22:01:24,659 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_false_false/_temporary
2016-05-17 22:01:24,659 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.205
2016-05-17 22:01:24,694 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:24,694 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:24,737 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.037
2016-05-17 22:01:24,738 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:24,738 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:24,753 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:24,753 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:24,753 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_true_false"]
2016-05-17 22:01:24,753 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:24,754 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:24,754 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:24,755 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ve/false_false_true_false
2016-05-17 22:01:24,813 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1683790361/.staging/job_local1683790361_0011/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:24,815 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1683790361/.staging/job_local1683790361_0011/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:24,846 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1683790361_0011/job_local1683790361_0011.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:24,846 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1683790361_0011/job_local1683790361_0011.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:24,848 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local1683790361_0011
2016-05-17 22:01:24,848 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:24,853 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:24,872 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:24,872 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:24,877 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: EF24B5C8B32442488E17DFB24F1AF84F, ordinal: 0
2016-05-17 22:01:24,878 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:24,878 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:24,878 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EF24B5C8B32442488E17DFB24F1AF84F, mem on start (mb), free: 256, total: 467, max: 672
2016-05-17 22:01:24,882 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EF24B5C8B32442488E17DFB24F1AF84F, mem on close (mb), free: 256, total: 467, max: 672
2016-05-17 22:01:24,893 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:24,894 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:24,899 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: DA019BDC1AFE482D91E3E2F5803525B1, ordinal: 1
2016-05-17 22:01:24,899 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:24,900 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_true_false"]
2016-05-17 22:01:24,900 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: DA019BDC1AFE482D91E3E2F5803525B1, mem on start (mb), free: 255, total: 467, max: 672
2016-05-17 22:01:24,911 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: DA019BDC1AFE482D91E3E2F5803525B1, mem on close (mb), free: 253, total: 467, max: 672
2016-05-17 22:01:24,927 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_true_false/_temporary
2016-05-17 22:01:24,927 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.173
2016-05-17 22:01:24,963 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:24,964 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:24,992 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.025
2016-05-17 22:01:24,992 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:24,993 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:25,005 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:25,006 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:25,006 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_true_false"]
2016-05-17 22:01:25,006 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:25,006 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:25,006 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:25,007 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ive/true_false_true_false
2016-05-17 22:01:25,061 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity863132463/.staging/job_local863132463_0012/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:25,063 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity863132463/.staging/job_local863132463_0012/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:25,094 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local863132463_0012/job_local863132463_0012.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:25,094 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local863132463_0012/job_local863132463_0012.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:25,095 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local863132463_0012
2016-05-17 22:01:25,095 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:25,100 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:25,118 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:25,118 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:25,124 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: C2EB679AE4244730B8A3A6A73CEDC61C, ordinal: 0
2016-05-17 22:01:25,125 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:25,125 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:25,125 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C2EB679AE4244730B8A3A6A73CEDC61C, mem on start (mb), free: 257, total: 467, max: 672
2016-05-17 22:01:25,130 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C2EB679AE4244730B8A3A6A73CEDC61C, mem on close (mb), free: 256, total: 467, max: 672
2016-05-17 22:01:25,142 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:25,143 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:25,148 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: FA535AC1097440D4BA909246AA64148C, ordinal: 1
2016-05-17 22:01:25,149 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:25,149 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_true_false"]
2016-05-17 22:01:25,149 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FA535AC1097440D4BA909246AA64148C, mem on start (mb), free: 254, total: 467, max: 672
2016-05-17 22:01:25,162 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FA535AC1097440D4BA909246AA64148C, mem on close (mb), free: 252, total: 467, max: 672
2016-05-17 22:01:25,182 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_true_false/_temporary
2016-05-17 22:01:25,183 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.177
2016-05-17 22:01:25,226 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:25,227 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:25,265 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.032
2016-05-17 22:01:25,266 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:25,266 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:25,282 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:25,282 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:25,282 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_true_false"]
2016-05-17 22:01:25,283 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:25,283 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:25,283 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:25,286 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...sive/true_true_true_false
2016-05-17 22:01:25,337 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity753778701/.staging/job_local753778701_0013/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:25,339 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity753778701/.staging/job_local753778701_0013/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:25,373 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local753778701_0013/job_local753778701_0013.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:25,374 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local753778701_0013/job_local753778701_0013.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:25,376 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local753778701_0013
2016-05-17 22:01:25,376 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:25,382 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:25,400 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:25,401 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:25,406 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: C4703BBBE2474022B561E0FA75146A01, ordinal: 0
2016-05-17 22:01:25,406 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:25,407 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:25,407 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C4703BBBE2474022B561E0FA75146A01, mem on start (mb), free: 255, total: 466, max: 672
2016-05-17 22:01:25,411 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C4703BBBE2474022B561E0FA75146A01, mem on close (mb), free: 255, total: 466, max: 672
2016-05-17 22:01:25,423 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:25,423 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:25,429 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: F4B4ECFF1B81436DAC64E3579CF7368B, ordinal: 1
2016-05-17 22:01:25,429 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:25,429 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_true_false"]
2016-05-17 22:01:25,429 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F4B4ECFF1B81436DAC64E3579CF7368B, mem on start (mb), free: 254, total: 466, max: 672
2016-05-17 22:01:25,440 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: F4B4ECFF1B81436DAC64E3579CF7368B, mem on close (mb), free: 253, total: 466, max: 672
2016-05-17 22:01:25,464 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_true_false/_temporary
2016-05-17 22:01:25,465 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.182
2016-05-17 22:01:25,502 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:25,503 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:25,532 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.026
2016-05-17 22:01:25,533 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:25,533 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:25,544 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:25,544 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:25,545 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_true_false"]
2016-05-17 22:01:25,545 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:25,545 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:25,545 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:25,547 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ive/false_true_true_false
2016-05-17 22:01:25,607 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1146736363/.staging/job_local1146736363_0014/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:25,609 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1146736363/.staging/job_local1146736363_0014/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:25,639 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1146736363_0014/job_local1146736363_0014.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:25,639 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1146736363_0014/job_local1146736363_0014.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:25,640 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local1146736363_0014
2016-05-17 22:01:25,641 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:25,647 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:25,668 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:25,668 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:25,674 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: EEBFB11BC11D493BB7FE712595626AAF, ordinal: 0
2016-05-17 22:01:25,675 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:25,676 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:25,676 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EEBFB11BC11D493BB7FE712595626AAF, mem on start (mb), free: 256, total: 466, max: 672
2016-05-17 22:01:25,681 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EEBFB11BC11D493BB7FE712595626AAF, mem on close (mb), free: 256, total: 466, max: 672
2016-05-17 22:01:25,693 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:25,693 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:25,698 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: D1CBD9DAFAD84CF6919EF6ED18321A0E, ordinal: 1
2016-05-17 22:01:25,698 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:25,698 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_true_false"]
2016-05-17 22:01:25,699 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D1CBD9DAFAD84CF6919EF6ED18321A0E, mem on start (mb), free: 254, total: 466, max: 672
2016-05-17 22:01:25,709 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D1CBD9DAFAD84CF6919EF6ED18321A0E, mem on close (mb), free: 253, total: 466, max: 672
2016-05-17 22:01:25,729 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_true_false/_temporary
2016-05-17 22:01:25,730 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.185
2016-05-17 22:01:25,763 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:25,763 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:25,789 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.023
2016-05-17 22:01:25,790 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:25,790 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:25,802 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:25,802 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:25,802 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_false_true"]
2016-05-17 22:01:25,802 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:25,803 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:25,803 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:25,804 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ive/false_null_false_true
2016-05-17 22:01:25,854 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity956549350/.staging/job_local956549350_0015/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:25,856 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity956549350/.staging/job_local956549350_0015/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:25,886 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local956549350_0015/job_local956549350_0015.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:25,886 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local956549350_0015/job_local956549350_0015.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:25,888 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local956549350_0015
2016-05-17 22:01:25,888 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:25,892 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:25,910 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:25,911 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:25,918 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: DE94DCBAC5394881BBDFFF3725BFF2D7, ordinal: 0
2016-05-17 22:01:25,919 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:25,919 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:25,919 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: DE94DCBAC5394881BBDFFF3725BFF2D7, mem on start (mb), free: 256, total: 466, max: 672
2016-05-17 22:01:25,923 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: DE94DCBAC5394881BBDFFF3725BFF2D7, mem on close (mb), free: 256, total: 466, max: 672
2016-05-17 22:01:25,935 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:25,935 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:25,940 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: D6A0C84F4D744E8E874221B2C13D2425, ordinal: 1
2016-05-17 22:01:25,940 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:25,940 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_false_true"]
2016-05-17 22:01:25,941 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D6A0C84F4D744E8E874221B2C13D2425, mem on start (mb), free: 255, total: 466, max: 672
2016-05-17 22:01:25,950 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D6A0C84F4D744E8E874221B2C13D2425, mem on close (mb), free: 253, total: 466, max: 672
2016-05-17 22:01:25,964 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_false_true/_temporary
2016-05-17 22:01:25,966 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.162
2016-05-17 22:01:26,001 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:26,001 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:26,027 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.022
2016-05-17 22:01:26,027 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:26,028 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:26,039 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:26,040 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:26,041 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_false_true"]
2016-05-17 22:01:26,041 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:26,042 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:26,042 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:26,043 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...sive/true_null_false_true
2016-05-17 22:01:26,095 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity795901699/.staging/job_local795901699_0016/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:26,097 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity795901699/.staging/job_local795901699_0016/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:26,127 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local795901699_0016/job_local795901699_0016.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:26,127 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local795901699_0016/job_local795901699_0016.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:26,128 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local795901699_0016
2016-05-17 22:01:26,129 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:26,132 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:26,152 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:26,152 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:26,161 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: EA93C305623F44D4A34CBC7CB1C54A80, ordinal: 0
2016-05-17 22:01:26,162 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:26,163 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:26,164 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EA93C305623F44D4A34CBC7CB1C54A80, mem on start (mb), free: 257, total: 467, max: 672
2016-05-17 22:01:26,170 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EA93C305623F44D4A34CBC7CB1C54A80, mem on close (mb), free: 257, total: 467, max: 672
2016-05-17 22:01:26,180 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:26,181 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:26,187 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: C11FD859553D4BD9909A076E97B068DA, ordinal: 1
2016-05-17 22:01:26,187 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:26,188 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_false_true"]
2016-05-17 22:01:26,188 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: C11FD859553D4BD9909A076E97B068DA, mem on start (mb), free: 255, total: 467, max: 672
2016-05-17 22:01:26,197 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: C11FD859553D4BD9909A076E97B068DA, mem on close (mb), free: 254, total: 467, max: 672
2016-05-17 22:01:26,215 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_false_true/_temporary
2016-05-17 22:01:26,216 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.176
2016-05-17 22:01:26,248 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:26,250 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:26,282 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.028
2016-05-17 22:01:26,283 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:26,283 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:26,298 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:26,299 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:26,300 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_true_true"]
2016-05-17 22:01:26,300 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:26,300 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:26,300 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:26,301 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...sive/false_null_true_true
2016-05-17 22:01:26,352 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity580345837/.staging/job_local580345837_0017/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:26,354 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity580345837/.staging/job_local580345837_0017/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:26,394 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local580345837_0017/job_local580345837_0017.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:26,394 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local580345837_0017/job_local580345837_0017.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:26,398 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local580345837_0017
2016-05-17 22:01:26,398 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:26,402 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:26,420 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:26,421 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:26,425 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: A8D797B87A7743D086BA4D6727C55813, ordinal: 0
2016-05-17 22:01:26,426 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:26,426 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:26,427 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A8D797B87A7743D086BA4D6727C55813, mem on start (mb), free: 258, total: 468, max: 672
2016-05-17 22:01:26,431 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: A8D797B87A7743D086BA4D6727C55813, mem on close (mb), free: 258, total: 468, max: 672
2016-05-17 22:01:26,443 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:26,443 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:26,448 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: DE111BF4639D4047BA0F54ACF2C92908, ordinal: 1
2016-05-17 22:01:26,448 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:26,449 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_true_true"]
2016-05-17 22:01:26,449 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: DE111BF4639D4047BA0F54ACF2C92908, mem on start (mb), free: 256, total: 468, max: 672
2016-05-17 22:01:26,459 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: DE111BF4639D4047BA0F54ACF2C92908, mem on close (mb), free: 255, total: 468, max: 672
2016-05-17 22:01:26,475 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_true_true/_temporary
2016-05-17 22:01:26,475 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.177
2016-05-17 22:01:26,510 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:26,510 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:26,538 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.025
2016-05-17 22:01:26,539 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:26,540 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:26,554 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:26,554 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:26,554 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_true_true"]
2016-05-17 22:01:26,554 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:26,555 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:26,555 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:26,557 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...nsive/true_null_true_true
2016-05-17 22:01:26,627 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity2038316662/.staging/job_local2038316662_0018/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:26,629 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity2038316662/.staging/job_local2038316662_0018/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:26,664 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local2038316662_0018/job_local2038316662_0018.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:26,664 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local2038316662_0018/job_local2038316662_0018.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:26,666 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local2038316662_0018
2016-05-17 22:01:26,667 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:26,671 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:26,689 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:26,690 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:26,695 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: C978ED84502D4997B880CBA222DB03D9, ordinal: 0
2016-05-17 22:01:26,696 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:26,696 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:26,696 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C978ED84502D4997B880CBA222DB03D9, mem on start (mb), free: 258, total: 468, max: 672
2016-05-17 22:01:26,702 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: C978ED84502D4997B880CBA222DB03D9, mem on close (mb), free: 258, total: 468, max: 672
2016-05-17 22:01:26,712 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:26,712 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:26,722 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: D00418A4B1A54459B29D4AFFB409D227, ordinal: 1
2016-05-17 22:01:26,723 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:26,723 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_true_true"]
2016-05-17 22:01:26,723 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D00418A4B1A54459B29D4AFFB409D227, mem on start (mb), free: 256, total: 468, max: 672
2016-05-17 22:01:26,733 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: D00418A4B1A54459B29D4AFFB409D227, mem on close (mb), free: 255, total: 468, max: 672
2016-05-17 22:01:26,745 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_true_true/_temporary
2016-05-17 22:01:26,746 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.192
2016-05-17 22:01:26,783 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:26,784 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:26,807 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.019
2016-05-17 22:01:26,808 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:26,809 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:26,822 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:26,822 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:26,822 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_false_true"]
2016-05-17 22:01:26,822 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:26,822 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:26,822 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:26,824 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ve/false_false_false_true
2016-05-17 22:01:26,880 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity856861340/.staging/job_local856861340_0019/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:26,883 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity856861340/.staging/job_local856861340_0019/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:26,912 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local856861340_0019/job_local856861340_0019.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:26,912 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local856861340_0019/job_local856861340_0019.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:26,913 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local856861340_0019
2016-05-17 22:01:26,914 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:26,917 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:26,936 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:26,937 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:26,941 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B05EAD15A6AB4500B69E7C744775AC0B, ordinal: 0
2016-05-17 22:01:26,942 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:26,943 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:26,943 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B05EAD15A6AB4500B69E7C744775AC0B, mem on start (mb), free: 258, total: 468, max: 672
2016-05-17 22:01:26,947 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B05EAD15A6AB4500B69E7C744775AC0B, mem on close (mb), free: 258, total: 468, max: 672
2016-05-17 22:01:26,958 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:26,958 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:26,963 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: B9A2751BD9154E6ABBF52A02B9546BF5, ordinal: 1
2016-05-17 22:01:26,964 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:26,964 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_false_true"]
2016-05-17 22:01:26,964 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B9A2751BD9154E6ABBF52A02B9546BF5, mem on start (mb), free: 256, total: 468, max: 672
2016-05-17 22:01:26,973 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B9A2751BD9154E6ABBF52A02B9546BF5, mem on close (mb), free: 255, total: 468, max: 672
2016-05-17 22:01:26,989 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_false_true/_temporary
2016-05-17 22:01:26,989 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.167
2016-05-17 22:01:27,027 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:27,028 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:27,061 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.027
2016-05-17 22:01:27,062 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:27,063 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:27,074 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:27,074 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:27,074 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_false_true"]
2016-05-17 22:01:27,074 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:27,075 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:27,075 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:27,076 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ive/true_false_false_true
2016-05-17 22:01:27,130 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1668196195/.staging/job_local1668196195_0020/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:27,132 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1668196195/.staging/job_local1668196195_0020/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:27,161 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1668196195_0020/job_local1668196195_0020.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:27,161 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1668196195_0020/job_local1668196195_0020.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:27,162 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local1668196195_0020
2016-05-17 22:01:27,163 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:27,166 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:27,185 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:27,186 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:27,193 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: F1E6FE590E614F9EA1A1BDA643093989, ordinal: 0
2016-05-17 22:01:27,194 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:27,194 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:27,195 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: F1E6FE590E614F9EA1A1BDA643093989, mem on start (mb), free: 258, total: 468, max: 672
2016-05-17 22:01:27,200 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: F1E6FE590E614F9EA1A1BDA643093989, mem on close (mb), free: 258, total: 468, max: 672
2016-05-17 22:01:27,212 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:27,212 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:27,216 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: DBEA6BA1AF1944E78E0C25918C38210C, ordinal: 1
2016-05-17 22:01:27,217 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:27,217 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_false_true"]
2016-05-17 22:01:27,217 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: DBEA6BA1AF1944E78E0C25918C38210C, mem on start (mb), free: 256, total: 468, max: 672
2016-05-17 22:01:27,227 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: DBEA6BA1AF1944E78E0C25918C38210C, mem on close (mb), free: 255, total: 468, max: 672
2016-05-17 22:01:27,240 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_false_true/_temporary
2016-05-17 22:01:27,241 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.166
2016-05-17 22:01:27,278 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:27,278 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:27,308 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.026
2016-05-17 22:01:27,309 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:27,309 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:27,318 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:27,319 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:27,319 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_false_true"]
2016-05-17 22:01:27,319 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:27,319 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:27,319 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:27,321 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...sive/true_true_false_true
2016-05-17 22:01:27,377 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity870090340/.staging/job_local870090340_0021/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:27,380 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity870090340/.staging/job_local870090340_0021/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:27,414 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local870090340_0021/job_local870090340_0021.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:27,415 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local870090340_0021/job_local870090340_0021.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:27,416 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local870090340_0021
2016-05-17 22:01:27,416 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:27,420 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:27,440 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:27,441 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:27,445 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: AD99E848300F43E2AD42DDB7C553CB54, ordinal: 0
2016-05-17 22:01:27,446 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:27,446 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:27,446 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: AD99E848300F43E2AD42DDB7C553CB54, mem on start (mb), free: 259, total: 469, max: 672
2016-05-17 22:01:27,450 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: AD99E848300F43E2AD42DDB7C553CB54, mem on close (mb), free: 259, total: 469, max: 672
2016-05-17 22:01:27,463 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:27,463 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:27,467 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: A9F9835037A34B40989CF7AFBD06DA35, ordinal: 1
2016-05-17 22:01:27,468 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:27,468 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_false_true"]
2016-05-17 22:01:27,468 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A9F9835037A34B40989CF7AFBD06DA35, mem on start (mb), free: 257, total: 469, max: 672
2016-05-17 22:01:27,477 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A9F9835037A34B40989CF7AFBD06DA35, mem on close (mb), free: 256, total: 469, max: 672
2016-05-17 22:01:27,493 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_false_true/_temporary
2016-05-17 22:01:27,493 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.175
2016-05-17 22:01:27,524 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:27,524 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:27,544 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.017
2016-05-17 22:01:27,545 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:27,545 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:27,554 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:27,554 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:27,554 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_false_true"]
2016-05-17 22:01:27,554 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:27,554 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:27,555 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:27,556 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ive/false_true_false_true
2016-05-17 22:01:27,603 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity605284041/.staging/job_local605284041_0022/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:27,604 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity605284041/.staging/job_local605284041_0022/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:27,633 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local605284041_0022/job_local605284041_0022.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:27,633 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local605284041_0022/job_local605284041_0022.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:27,635 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local605284041_0022
2016-05-17 22:01:27,635 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:27,640 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:27,660 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:27,661 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:27,666 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B2D831E0C21A49709EA478D0FDCECED4, ordinal: 0
2016-05-17 22:01:27,667 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:27,667 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:27,668 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B2D831E0C21A49709EA478D0FDCECED4, mem on start (mb), free: 259, total: 469, max: 672
2016-05-17 22:01:27,673 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B2D831E0C21A49709EA478D0FDCECED4, mem on close (mb), free: 259, total: 469, max: 672
2016-05-17 22:01:27,686 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:27,686 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:27,689 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: B9DB1B0583F443B49A1417E08D7CFE8A, ordinal: 1
2016-05-17 22:01:27,690 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:27,690 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_false_true"]
2016-05-17 22:01:27,690 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B9DB1B0583F443B49A1417E08D7CFE8A, mem on start (mb), free: 257, total: 469, max: 672
2016-05-17 22:01:27,699 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B9DB1B0583F443B49A1417E08D7CFE8A, mem on close (mb), free: 256, total: 469, max: 672
2016-05-17 22:01:27,712 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_false_true/_temporary
2016-05-17 22:01:27,713 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.158
2016-05-17 22:01:27,748 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:27,748 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:27,769 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.017
2016-05-17 22:01:27,769 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:27,770 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:27,777 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:27,778 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:27,778 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_true_true"]
2016-05-17 22:01:27,778 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:27,779 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:27,779 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:27,780 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...ive/false_false_true_true
2016-05-17 22:01:27,832 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity801313003/.staging/job_local801313003_0023/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:27,833 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity801313003/.staging/job_local801313003_0023/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:27,861 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local801313003_0023/job_local801313003_0023.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:27,862 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local801313003_0023/job_local801313003_0023.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:27,863 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local801313003_0023
2016-05-17 22:01:27,863 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:27,868 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:27,886 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:27,887 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:27,892 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: E40D4D82DF3246FE9DA77664CFA3C28B, ordinal: 0
2016-05-17 22:01:27,893 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:27,893 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:27,893 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: E40D4D82DF3246FE9DA77664CFA3C28B, mem on start (mb), free: 259, total: 469, max: 672
2016-05-17 22:01:27,898 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: E40D4D82DF3246FE9DA77664CFA3C28B, mem on close (mb), free: 259, total: 469, max: 672
2016-05-17 22:01:27,908 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:27,909 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:27,912 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: B0E9CF14752F45B094EA4E936C473A59, ordinal: 1
2016-05-17 22:01:27,913 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:27,914 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_true_true"]
2016-05-17 22:01:27,914 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B0E9CF14752F45B094EA4E936C473A59, mem on start (mb), free: 257, total: 469, max: 672
2016-05-17 22:01:27,924 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B0E9CF14752F45B094EA4E936C473A59, mem on close (mb), free: 256, total: 469, max: 672
2016-05-17 22:01:27,939 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_true_true/_temporary
2016-05-17 22:01:27,940 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.162
2016-05-17 22:01:27,973 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:27,974 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:27,997 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.020
2016-05-17 22:01:27,998 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:27,999 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:28,006 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:28,006 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:28,006 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_true_true"]
2016-05-17 22:01:28,006 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:28,007 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:28,007 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:28,007 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...sive/true_false_true_true
2016-05-17 22:01:28,056 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1680607294/.staging/job_local1680607294_0024/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:28,057 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity1680607294/.staging/job_local1680607294_0024/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:28,085 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1680607294_0024/job_local1680607294_0024.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:28,085 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local1680607294_0024/job_local1680607294_0024.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:28,086 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local1680607294_0024
2016-05-17 22:01:28,086 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:28,090 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:28,108 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:28,108 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:28,112 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: DC76FEC814D848729EB58B02D562A9BB, ordinal: 0
2016-05-17 22:01:28,112 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:28,113 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:28,113 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: DC76FEC814D848729EB58B02D562A9BB, mem on start (mb), free: 259, total: 469, max: 672
2016-05-17 22:01:28,117 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: DC76FEC814D848729EB58B02D562A9BB, mem on close (mb), free: 259, total: 469, max: 672
2016-05-17 22:01:28,126 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:28,126 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:28,130 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: A25A3FA85B52404C9BE12AAD78BF4B16, ordinal: 1
2016-05-17 22:01:28,131 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:28,132 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_true_true"]
2016-05-17 22:01:28,132 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A25A3FA85B52404C9BE12AAD78BF4B16, mem on start (mb), free: 257, total: 469, max: 672
2016-05-17 22:01:28,149 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: A25A3FA85B52404C9BE12AAD78BF4B16, mem on close (mb), free: 256, total: 469, max: 672
2016-05-17 22:01:28,161 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_true_true/_temporary
2016-05-17 22:01:28,161 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.155
2016-05-17 22:01:28,194 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:28,195 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:28,218 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.020
2016-05-17 22:01:28,219 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:28,219 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:28,225 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:28,226 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:28,226 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_true_true"]
2016-05-17 22:01:28,226 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:28,227 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:28,227 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:28,228 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...nsive/true_true_true_true
2016-05-17 22:01:28,277 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity752238502/.staging/job_local752238502_0025/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:28,278 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity752238502/.staging/job_local752238502_0025/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:28,313 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local752238502_0025/job_local752238502_0025.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:28,314 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local752238502_0025/job_local752238502_0025.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:28,316 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local752238502_0025
2016-05-17 22:01:28,316 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:28,319 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:28,340 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:28,340 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:28,345 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: EA13509AC3674B819ED56F2FE3DE387E, ordinal: 0
2016-05-17 22:01:28,346 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:28,346 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:28,346 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EA13509AC3674B819ED56F2FE3DE387E, mem on start (mb), free: 260, total: 470, max: 672
2016-05-17 22:01:28,351 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: EA13509AC3674B819ED56F2FE3DE387E, mem on close (mb), free: 260, total: 470, max: 672
2016-05-17 22:01:28,361 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:28,361 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:28,365 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: B394474D88064D628493716C9EBE0669, ordinal: 1
2016-05-17 22:01:28,365 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:28,365 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_true_true"]
2016-05-17 22:01:28,365 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B394474D88064D628493716C9EBE0669, mem on start (mb), free: 258, total: 470, max: 672
2016-05-17 22:01:28,374 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: B394474D88064D628493716C9EBE0669, mem on close (mb), free: 256, total: 470, max: 672
2016-05-17 22:01:28,393 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_true_true/_temporary
2016-05-17 22:01:28,393 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.167
2016-05-17 22:01:28,423 INFO  util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-17 22:01:28,423 INFO  planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop2-mr1/3.1.0/fdadb6ac788ea19f3412b9569a43a7635318b0c5/cascading-hadoop2-mr1-3.1.0.jar
2016-05-17 22:01:28,442 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.015
2016-05-17 22:01:28,443 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-17 22:01:28,443 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-17 22:01:28,454 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting
2016-05-17 22:01:28,454 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  source: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:28,454 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  sink: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_true_true"]
2016-05-17 22:01:28,455 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  parallel execution of steps is enabled: false
2016-05-17 22:01:28,455 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  executing total steps: 1
2016-05-17 22:01:28,455 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  allocating management threads: 1
2016-05-17 22:01:28,455 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] starting step: (1/1) ...sive/false_true_true_true
2016-05-17 22:01:28,504 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity615295793/.staging/job_local615295793_0026/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:28,504 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/tmp/cascading/staging/teamcity615295793/.staging/job_local615295793_0026/job.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:28,532 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local615295793_0026/job_local615295793_0026.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.retry.interval;  Ignoring.
2016-05-17 22:01:28,532 WARN  conf.Configuration (Configuration.java:loadProperty(2172)) - file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/tmp/mapred/local/localRunner/teamcity/job_local615295793_0026/job_local615295793_0026.xml:an attempt to override final parameter: mapreduce.job.end-notification.max.attempts;  Ignoring.
2016-05-17 22:01:28,533 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] submitted hadoop job: job_local615295793_0026
2016-05-17 22:01:28,533 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort] tracking url: http://localhost:8080/
2016-05-17 22:01:28,545 INFO  io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt
2016-05-17 22:01:28,566 INFO  hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-17 22:01:28,566 INFO  hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-17 22:01:28,571 INFO  hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: DC2D9155B2B74732A40407D01CED7925, ordinal: 0
2016-05-17 22:01:28,572 INFO  hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/classes/test/data/lhs+rhs-cross.txt"]
2016-05-17 22:01:28,572 INFO  hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:28,572 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: DC2D9155B2B74732A40407D01CED7925, mem on start (mb), free: 260, total: 470, max: 672
2016-05-17 22:01:28,575 INFO  hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: DC2D9155B2B74732A40407D01CED7925, mem on close (mb), free: 260, total: 470, max: 672
2016-05-17 22:01:28,584 INFO  hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-17 22:01:28,584 INFO  hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-17 22:01:28,587 INFO  hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: C33A548AC6E741B195F3CC2BA211D9B1, ordinal: 1
2016-05-17 22:01:28,588 INFO  hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(comprehensivesort)[by:[{1}:'num']]
2016-05-17 22:01:28,588 INFO  hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['line']->['num', 'lower', 'upper']]"]["/opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_true_true"]
2016-05-17 22:01:28,588 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: C33A548AC6E741B195F3CC2BA211D9B1, mem on start (mb), free: 258, total: 470, max: 672
2016-05-17 22:01:28,597 INFO  hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: C33A548AC6E741B195F3CC2BA211D9B1, mem on close (mb), free: 257, total: 470, max: 672
2016-05-17 22:01:28,618 INFO  util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output/hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_true_true/_temporary
2016-05-17 22:01:28,619 INFO  flow.Flow (BaseFlow.java:logInfo(1504)) - [comprehensivesort]  completed in: 00:00.164
2016-05-17 22:01:28,640 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_true_true
2016-05-17 22:01:28,640 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_true_false
2016-05-17 22:01:28,641 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_true_false
2016-05-17 22:01:28,641 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_false_true
2016-05-17 22:01:28,641 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_false_true
2016-05-17 22:01:28,641 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_false_false_false
2016-05-17 22:01:28,642 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_true_false
2016-05-17 22:01:28,642 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_true_false
2016-05-17 22:01:28,642 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_true_true
2016-05-17 22:01:28,643 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_true_false
2016-05-17 22:01:28,643 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_true_true
2016-05-17 22:01:28,643 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_true_true
2016-05-17 22:01:28,644 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_false_false
2016-05-17 22:01:28,644 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_true_true
2016-05-17 22:01:28,644 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_false_false
2016-05-17 22:01:28,645 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_false_true
2016-05-17 22:01:28,645 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_false_true
2016-05-17 22:01:28,645 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_null_false_true
2016-05-17 22:01:28,645 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_true_true
2016-05-17 22:01:28,646 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_null_true_false
2016-05-17 22:01:28,646 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_false_false
2016-05-17 22:01:28,646 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_true_false_false
2016-05-17 22:01:28,647 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/true_true_false_true
2016-05-17 22:01:28,647 INFO  cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/9c1cc778dcd1fc2f/apache-2.2.x/build/test/output//hadoop2-mr1/sortedvaluesplatform/testComprehensiveGroupBy/comprehensive/false_false_false_false