2016-05-18 02:01:10,850 INFO platform.PlatformRunner (PlatformRunner.java:getPlatformClassesFromClasspath(197)) - found 1 test platforms from classpath
2016-05-18 02:01:10,865 INFO platform.PlatformRunner (PlatformRunner.java:addPlatform(246)) - adding test: cascading.flow.FlowStrategiesPlatformTest, with platform: hadoop
2016-05-18 02:01:10,888 INFO hadoop.HadoopPlatform (HadoopPlatform.java:setUp(127)) - not using cluster
2016-05-18 02:01:11,128 INFO platform.TestPlatform (TestPlatform.java:getGlobalProperties(75)) - platform property overrides:
2016-05-18 02:01:11,220 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 02:01:11,221 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.1.0/86eabf0febb15d07d5285b321b1f6b06e25aa664/cascading-hadoop-3.1.0.jar
2016-05-18 02:01:11,337 INFO property.AppProps (AppProps.java:getAppID(177)) - using app.id: 3FD3369CE6CA46F19260BF7B9212E36D
2016-05-18 02:01:11,471 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.092
2016-05-18 02:01:11,475 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 02:01:11,475 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 02:01:11,626 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink is marked for delete
2016-05-18 02:01:11,630 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 02:01:11,630 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink is marked for delete
2016-05-18 02:01:11,631 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 02:01:11,684 INFO util.Version (Version.java:printBanner(82)) - Concurrent, Inc - Cascading 3.1.0
2016-05-18 02:01:11,685 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 02:01:11,687 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/classes/test/data/apache.10.txt"]
2016-05-18 02:01:11,688 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesReplace/replace"]
2016-05-18 02:01:11,688 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: false
2016-05-18 02:01:11,688 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 02:01:11,689 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 02:01:11,690 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...StrategiesReplace/replace
2016-05-18 02:01:11,721 WARN util.NativeCodeLoader (NativeCodeLoader.java:<clinit>(52)) - Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
2016-05-18 02:01:11,761 WARN snappy.LoadSnappy (LoadSnappy.java:<clinit>(46)) - Snappy native library not loaded
2016-05-18 02:01:11,982 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_local_0001
2016-05-18 02:01:11,983 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://localhost:8080/
2016-05-18 02:01:12,046 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/classes/test/data/apache.10.txt
2016-05-18 02:01:12,061 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-18 02:01:12,062 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-18 02:01:12,075 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: E58EB615608D414A98FECBA1576D70A0, ordinal: 0
2016-05-18 02:01:12,119 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/classes/test/data/apache.10.txt"]
2016-05-18 02:01:12,120 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesReplace/replace"]
2016-05-18 02:01:12,121 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: E58EB615608D414A98FECBA1576D70A0, mem on start (mb), free: 190, total: 223, max: 672
2016-05-18 02:01:12,130 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: E58EB615608D414A98FECBA1576D70A0, mem on close (mb), free: 189, total: 223, max: 672
2016-05-18 02:01:15,030 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesReplace/replace/_temporary
2016-05-18 02:01:15,032 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:03.343
2016-05-18 02:01:15,033 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink is marked for delete
2016-05-18 02:01:15,033 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 02:01:15,033 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink is marked for delete
2016-05-18 02:01:15,034 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 02:01:15,080 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output//hadoop/flowstrategiesplatform/testSkipStrategiesReplace/replace
2016-05-18 02:01:15,107 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 02:01:15,108 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.1.0/86eabf0febb15d07d5285b321b1f6b06e25aa664/cascading-hadoop-3.1.0.jar
2016-05-18 02:01:15,188 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.073
2016-05-18 02:01:15,190 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 02:01:15,190 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 02:01:15,207 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink does not exist
2016-05-18 02:01:15,209 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink does not exist
2016-05-18 02:01:15,211 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 02:01:15,211 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/classes/test/data/apache.10.txt"]
2016-05-18 02:01:15,212 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesKeep/keep"]
2016-05-18 02:01:15,213 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: false
2016-05-18 02:01:15,214 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 02:01:15,214 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 02:01:15,215 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...stSkipStrategiesKeep/keep
2016-05-18 02:01:15,300 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_local_0002
2016-05-18 02:01:15,300 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://localhost:8080/
2016-05-18 02:01:15,304 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/classes/test/data/apache.10.txt
2016-05-18 02:01:15,309 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-18 02:01:15,309 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-18 02:01:15,321 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: F5B6BA270EE747E6B1FFF1B6BD0B4467, ordinal: 0
2016-05-18 02:01:15,322 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/classes/test/data/apache.10.txt"]
2016-05-18 02:01:15,323 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesKeep/keep"]
2016-05-18 02:01:15,323 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: F5B6BA270EE747E6B1FFF1B6BD0B4467, mem on start (mb), free: 179, total: 223, max: 672
2016-05-18 02:01:15,326 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: F5B6BA270EE747E6B1FFF1B6BD0B4467, mem on close (mb), free: 179, total: 223, max: 672
2016-05-18 02:01:18,316 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesKeep/keep/_temporary
2016-05-18 02:01:18,316 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:03.104
2016-05-18 02:01:18,319 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed May 18 02:01:18 UTC 2016
2016-05-18 02:01:18,323 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source modification date at: Tue May 17 23:39:48 UTC 2016
2016-05-18 02:01:18,324 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed May 18 02:01:18 UTC 2016
2016-05-18 02:01:18,358 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output//hadoop/flowstrategiesplatform/testSkipStrategiesKeep/keep
2016-05-18 02:01:18,383 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 02:01:18,383 INFO planner.HadoopPlanner (HadoopPlanner.java:initialize(176)) - using application jar: /opt/concurrent/home/teamcity/.gradle/caches/modules-2/files-2.1/cascading/cascading-hadoop/3.1.0/86eabf0febb15d07d5285b321b1f6b06e25aa664/cascading-hadoop-3.1.0.jar
2016-05-18 02:01:18,493 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.100
2016-05-18 02:01:18,496 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-18 02:01:18,496 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, result was selected using: 'default comparator: selects plan with fewest steps and fewest nodes'
2016-05-18 02:01:18,536 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 02:01:18,536 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/classes/test/data/apache.10.txt"]
2016-05-18 02:01:18,537 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output/hadoop/flowstrategiesplatform/testFlowStepStrategy/simple"]
2016-05-18 02:01:18,537 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: false
2016-05-18 02:01:18,539 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 02:01:18,539 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 02:01:18,540 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...stFlowStepStrategy/simple
2016-05-18 02:01:18,608 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_local_0003
2016-05-18 02:01:18,608 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://localhost:8080/
2016-05-18 02:01:18,613 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/classes/test/data/apache.10.txt
2016-05-18 02:01:18,751 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-18 02:01:18,751 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-18 02:01:18,765 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: E745B53470C6485C927B04317D9DCD17, ordinal: 0
2016-05-18 02:01:18,772 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/classes/test/data/apache.10.txt"]
2016-05-18 02:01:18,772 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'ip']]
2016-05-18 02:01:18,772 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: E745B53470C6485C927B04317D9DCD17, mem on start (mb), free: 64, total: 223, max: 672
2016-05-18 02:01:18,778 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: E745B53470C6485C927B04317D9DCD17, mem on close (mb), free: 64, total: 223, max: 672
2016-05-18 02:01:21,629 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-18 02:01:21,629 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-18 02:01:21,641 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: BBA4A2E604584CE18414236122CBBBA7, ordinal: 1
2016-05-18 02:01:21,644 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'ip']]
2016-05-18 02:01:21,645 INFO hadoop.FlowReducer (FlowReducer.java:configure(107)) - sinking to: Hfs["TextLine[['offset', 'line' | long, String]->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output/hadoop/flowstrategiesplatform/testFlowStepStrategy/simple"]
2016-05-18 02:01:21,646 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: BBA4A2E604584CE18414236122CBBBA7, mem on start (mb), free: 62, total: 223, max: 672
2016-05-18 02:01:21,666 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: BBA4A2E604584CE18414236122CBBBA7, mem on close (mb), free: 61, total: 223, max: 672
2016-05-18 02:01:24,625 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output/hadoop/flowstrategiesplatform/testFlowStepStrategy/simple/_temporary
2016-05-18 02:01:24,626 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:06.088
2016-05-18 02:01:24,638 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.0.x/build/test/output//hadoop/flowstrategiesplatform/testFlowStepStrategy/simple