2016-05-18 04:04:15,955 INFO platform.PlatformRunner (PlatformRunner.java:getPlatformClassesFromClasspath(197)) - found 1 test platforms from classpath
2016-05-18 04:04:15,971 INFO platform.PlatformRunner (PlatformRunner.java:addPlatform(246)) - adding test: cascading.flow.FlowStrategiesPlatformTest, with platform: hadoop
2016-05-18 04:04:15,994 INFO hadoop.HadoopPlatform (HadoopPlatform.java:setUp(127)) - not using cluster
2016-05-18 04:04:16,215 INFO platform.TestPlatform (TestPlatform.java:getGlobalProperties(75)) - platform property overrides:
2016-05-18 04:04:16,302 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 04:04:16,304 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 04:04:16,407 INFO property.AppProps (AppProps.java:getAppID(177)) - using app.id: FCD21E94A19845FE904F24DAE6814AA2
2016-05-18 04:04:16,563 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.112
2016-05-18 04:04:16,566 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 04:04:16,567 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 04:04:16,681 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink is marked for delete
2016-05-18 04:04:16,685 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 04:04:16,686 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink is marked for delete
2016-05-18 04:04:16,687 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 04:04:16,729 INFO util.Version (Version.java:printBanner(82)) - Concurrent, Inc - Cascading 3.1.0
2016-05-18 04:04:16,730 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 04:04:16,731 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.1.x/build/classes/test/data/apache.10.txt"]
2016-05-18 04:04:16,732 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.1.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesReplace/replace"]
2016-05-18 04:04:16,732 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: false
2016-05-18 04:04:16,732 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 04:04:16,733 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 04:04:16,734 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...StrategiesReplace/replace
2016-05-18 04:04:16,776 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 04:04:16,832 WARN snappy.LoadSnappy (LoadSnappy.java:<clinit>(46)) - Snappy native library not loaded
2016-05-18 04:04:17,103 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_local_0001
2016-05-18 04:04:17,104 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://localhost:8080/
2016-05-18 04:04:17,197 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/classes/test/data/apache.10.txt
2016-05-18 04:04:17,213 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-18 04:04:17,213 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-18 04:04:17,226 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: ADFDC44C60394866849E3453D9065BF9, ordinal: 0
2016-05-18 04:04:17,272 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.1.x/build/classes/test/data/apache.10.txt"]
2016-05-18 04:04:17,273 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.1.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesReplace/replace"]
2016-05-18 04:04:17,273 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: ADFDC44C60394866849E3453D9065BF9, mem on start (mb), free: 185, total: 223, max: 672
2016-05-18 04:04:17,284 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: ADFDC44C60394866849E3453D9065BF9, mem on close (mb), free: 184, total: 223, max: 672
2016-05-18 04:04:17,309 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesReplace/replace/_temporary
2016-05-18 04:04:17,311 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:00.577
2016-05-18 04:04:17,312 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink is marked for delete
2016-05-18 04:04:17,312 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 04:04:17,313 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink is marked for delete
2016-05-18 04:04:17,314 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed Dec 31 23:59:59 UTC 1969
2016-05-18 04:04:17,353 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/test/output//hadoop/flowstrategiesplatform/testSkipStrategiesReplace/replace
2016-05-18 04:04:17,379 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 04:04:17,380 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 04:04:17,448 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.059
2016-05-18 04:04:17,451 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 1
2016-05-18 04:04:17,451 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 04:04:17,471 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink does not exist
2016-05-18 04:04:17,473 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] at least one sink does not exist
2016-05-18 04:04:17,474 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 04:04:17,475 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.1.x/build/classes/test/data/apache.10.txt"]
2016-05-18 04:04:17,476 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.1.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesKeep/keep"]
2016-05-18 04:04:17,478 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: false
2016-05-18 04:04:17,478 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 04:04:17,479 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 04:04:17,480 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...stSkipStrategiesKeep/keep
2016-05-18 04:04:17,612 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_local_0002
2016-05-18 04:04:17,614 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://localhost:8080/
2016-05-18 04:04:17,618 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/classes/test/data/apache.10.txt
2016-05-18 04:04:17,624 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-18 04:04:17,625 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-18 04:04:17,641 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: FBFC0042938B48BAA695E0EDDBEE6531, ordinal: 0
2016-05-18 04:04:17,642 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.1.x/build/classes/test/data/apache.10.txt"]
2016-05-18 04:04:17,642 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.1.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesKeep/keep"]
2016-05-18 04:04:17,643 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FBFC0042938B48BAA695E0EDDBEE6531, mem on start (mb), free: 170, total: 223, max: 672
2016-05-18 04:04:17,645 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: FBFC0042938B48BAA695E0EDDBEE6531, mem on close (mb), free: 170, total: 223, max: 672
2016-05-18 04:04:17,666 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/test/output/hadoop/flowstrategiesplatform/testSkipStrategiesKeep/keep/_temporary
2016-05-18 04:04:17,666 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:00.191
2016-05-18 04:04:17,669 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed May 18 04:04:17 UTC 2016
2016-05-18 04:04:17,672 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source modification date at: Wed May 18 02:50:02 UTC 2016
2016-05-18 04:04:17,674 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] sink oldest modified date: Wed May 18 04:04:17 UTC 2016
2016-05-18 04:04:17,717 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/test/output//hadoop/flowstrategiesplatform/testSkipStrategiesKeep/keep
2016-05-18 04:04:17,747 INFO util.Util (Util.java:findMainClass(1374)) - using default application jar, may cause class not found exceptions on the cluster
2016-05-18 04:04:17,748 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 04:04:17,852 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executed rule registry: MapReduceHadoopRuleRegistry, completed as: SUCCESS, in: 00:00.093
2016-05-18 04:04:17,855 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] rule registry: MapReduceHadoopRuleRegistry, supports assembly with steps: 1, nodes: 2
2016-05-18 04:04:17,855 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 04:04:17,903 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting
2016-05-18 04:04:17,904 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] source: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/classes/test/data/apache.10.txt"]
2016-05-18 04:04:17,905 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.1.x/build/test/output/hadoop/flowstrategiesplatform/testFlowStepStrategy/simple"]
2016-05-18 04:04:17,906 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] parallel execution of steps is enabled: false
2016-05-18 04:04:17,906 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] executing total steps: 1
2016-05-18 04:04:17,907 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] allocating management threads: 1
2016-05-18 04:04:17,908 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] starting step: (1/1) ...stFlowStepStrategy/simple
2016-05-18 04:04:17,957 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] submitted hadoop job: job_local_0003
2016-05-18 04:04:17,957 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] tracking url: http://localhost:8080/
2016-05-18 04:04:17,961 INFO io.MultiInputSplit (MultiInputSplit.java:readFields(161)) - current split input path: file:/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/classes/test/data/apache.10.txt
2016-05-18 04:04:18,114 INFO hadoop.FlowMapper (FlowMapper.java:configure(75)) - cascading version: 3.1.0
2016-05-18 04:04:18,115 INFO hadoop.FlowMapper (FlowMapper.java:configure(76)) - child jvm opts: -Xmx200m
2016-05-18 04:04:18,126 INFO hadoop.FlowMapper (FlowMapper.java:configure(87)) - flow node id: B6A7AF96FCDA488DAF02BB3754B9DA45, ordinal: 0
2016-05-18 04:04:18,133 INFO hadoop.FlowMapper (FlowMapper.java:configure(94)) - sourcing from: Hfs["TextLine[['offset', 'line']->[ALL]]"]["/opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/classes/test/data/apache.10.txt"]
2016-05-18 04:04:18,133 INFO hadoop.FlowMapper (FlowMapper.java:configure(97)) - sinking to: GroupBy(test)[by:[{1}:'ip']]
2016-05-18 04:04:18,134 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B6A7AF96FCDA488DAF02BB3754B9DA45, mem on start (mb), free: 110, total: 221, max: 672
2016-05-18 04:04:18,139 INFO hadoop.FlowMapper (LogUtil.java:logMemory(59)) - flow node id: B6A7AF96FCDA488DAF02BB3754B9DA45, mem on close (mb), free: 110, total: 221, max: 672
2016-05-18 04:04:18,168 INFO hadoop.FlowReducer (FlowReducer.java:configure(83)) - cascading version: 3.1.0
2016-05-18 04:04:18,168 INFO hadoop.FlowReducer (FlowReducer.java:configure(84)) - child jvm opts: -Xmx200m
2016-05-18 04:04:18,179 INFO hadoop.FlowReducer (FlowReducer.java:configure(97)) - flow node id: FAFF915A9F4C4A588016A17967EF3A41, ordinal: 1
2016-05-18 04:04:18,182 INFO hadoop.FlowReducer (FlowReducer.java:configure(104)) - sourcing from: GroupBy(test)[by:[{1}:'ip']]
2016-05-18 04:04:18,183 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.1.x/build/test/output/hadoop/flowstrategiesplatform/testFlowStepStrategy/simple"]
2016-05-18 04:04:18,183 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FAFF915A9F4C4A588016A17967EF3A41, mem on start (mb), free: 108, total: 221, max: 672
2016-05-18 04:04:18,201 INFO hadoop.FlowReducer (LogUtil.java:logMemory(59)) - flow node id: FAFF915A9F4C4A588016A17967EF3A41, mem on close (mb), free: 107, total: 221, max: 672
2016-05-18 04:04:18,220 INFO util.Hadoop18TapUtil (Hadoop18TapUtil.java:cleanTempPath(226)) - deleting temp path /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/test/output/hadoop/flowstrategiesplatform/testFlowStepStrategy/simple/_temporary
2016-05-18 04:04:18,221 INFO flow.Flow (BaseFlow.java:logInfo(1504)) - [test] completed in: 00:00.317
2016-05-18 04:04:18,232 INFO cascading.PlatformTestCase (PlatformTestCase.java:tearDown(189)) - copying to local /opt/buildAgent-8.0.3/work/ddea2c91addb6250/apache-1.1.x/build/test/output//hadoop/flowstrategiesplatform/testFlowStepStrategy/simple