设为首页 收藏本站
查看: 513|回复: 0

[经验分享] hadoop学习1——job执行过程

[复制链接]

尚未签到

发表于 2016-12-11 08:13:08 | 显示全部楼层 |阅读模式
  接触hadoop半年多了,主要使用hadoop+hive做数据分析。部署和使用现在都没什么问题了,但是就是对其内部原理不是非常清楚,所以准备从头从源码开始系统学习,把学习过程中的问题和自己的理解记录在此。
  下面是一段调试wordcount:
  环境:windows + cygwin + eclipse(怎么搭建环境、和搭建过程中遇到的问题以后有空再写,现在主要学习一下hadoop的运行原理),伪分布式模式
  测试数据:
  t1.txt:

hello world! hello ufida!
yes i do!
say something.
  t2.txt:

cow is a cow.
word count job test.
  调试代码:

public class WordCount {
static Logger log = Logger.getLogger(WordCount.class);
public static class Map extends MapReduceBase implements Mapper<LongWritable, Text, Text, IntWritable> {
private final static IntWritable one = new IntWritable(1);
private Text word = new Text();
public void map(LongWritable key, Text value,
OutputCollector<Text, IntWritable> output, Reporter reporter)
throws IOException {
log.info("map 进程:" + Thread.currentThread().toString());
log.info("map 参数:key:" + key.get() + ";value:" + value);
String line = value.toString();
StringTokenizer tokenizer = new StringTokenizer(line);
while (tokenizer.hasMoreTokens()) {
word.set(tokenizer.nextToken());
log.info("word:" + word.toString());
output.collect(word, one);
}
}
}
public static class Reduce extends MapReduceBase implements Reducer<Text, IntWritable, Text, IntWritable> {
public void reduce(Text key, Iterator<IntWritable> values,
OutputCollector<Text, IntWritable> output, Reporter reporter)
throws IOException {
log.info("reduce 进程:" + Thread.currentThread().toString());
String s = "";
int sum = 0;
while (values.hasNext()) {
IntWritable i = values.next();
s = s + "[" + i.get() + "]";
sum += i.get();
}
log.info("reduce 参数:key:" + key.toString() + ";values:" + s);
output.collect(key, new IntWritable(sum));
}
}
public static void main(String[] args) throws Exception {
log.info("单词统计...");
JobConf conf = new JobConf(WordCount.class);
log.info("jar包位置:" + conf.getJar());
conf.setJobName("wordcount");
conf.setOutputKeyClass(Text.class);
conf.setOutputValueClass(IntWritable.class);
conf.setMapperClass(Map.class);
conf.setCombinerClass(Reduce.class);
conf.setReducerClass(Reduce.class);
conf.setInputFormat(TextInputFormat.class);
conf.setOutputFormat(TextOutputFormat.class);
FileInputFormat.setInputPaths(conf, new Path("/temp/in"));
FileOutputFormat.setOutputPath(conf, new Path("/temp/out"));
JobClient.runJob(conf);
}
}
  运行日志:

12/02/09 11:08:05 INFO test.WordCount: 单词统计...
12/02/09 11:08:05 INFO test.WordCount: jar包位置:D:\workspaces\eclipseWorkspace\.metadata\.plugins\org.apache.hadoop.eclipse\hadoopTest_WordCount.java-234599505300279609.jar
12/02/09 11:08:06 INFO jvm.JvmMetrics: Initializing JVM Metrics with processName=JobTracker, sessionId=
12/02/09 11:08:06 WARN mapred.JobClient: Use GenericOptionsParser for parsing the arguments. Applications should implement Tool for the same.
12/02/09 11:08:06 INFO mapred.FileInputFormat: Total input paths to process : 2
12/02/09 11:08:06 INFO mapred.JobClient: Running job: job_local_0001
12/02/09 11:08:06 INFO mapred.FileInputFormat: Total input paths to process : 2
12/02/09 11:08:06 INFO mapred.MapTask: numReduceTasks: 1
12/02/09 11:08:06 INFO mapred.MapTask: io.sort.mb = 100
12/02/09 11:08:06 INFO mapred.MapTask: data buffer = 79691776/99614720
12/02/09 11:08:06 INFO mapred.MapTask: record buffer = 262144/327680
12/02/09 11:08:06 INFO test.WordCount: map 进程:Thread[Thread-14,5,main]
12/02/09 11:08:06 INFO test.WordCount: map 参数:key:0;value:hello world! hello ufida!
12/02/09 11:08:06 INFO test.WordCount: word:hello
12/02/09 11:08:06 INFO test.WordCount: word:world!
12/02/09 11:08:06 INFO test.WordCount: word:hello
12/02/09 11:08:06 INFO test.WordCount: word:ufida!
12/02/09 11:08:06 INFO test.WordCount: map 进程:Thread[Thread-14,5,main]
12/02/09 11:08:06 INFO test.WordCount: map 参数:key:27;value:yes i do!
12/02/09 11:08:06 INFO test.WordCount: word:yes
12/02/09 11:08:06 INFO test.WordCount: word:i
12/02/09 11:08:06 INFO test.WordCount: word:do!
12/02/09 11:08:06 INFO test.WordCount: map 进程:Thread[Thread-14,5,main]
12/02/09 11:08:06 INFO test.WordCount: map 参数:key:38;value:say something.
12/02/09 11:08:06 INFO test.WordCount: word:say
12/02/09 11:08:06 INFO test.WordCount: word:something.
12/02/09 11:08:06 INFO mapred.MapTask: Starting flush of map output
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:do!;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:hello;values:[1][1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:i;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:say;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:something.;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:ufida!;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:world!;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:yes;values:[1]
12/02/09 11:08:07 INFO mapred.MapTask: Finished spill 0
12/02/09 11:08:07 INFO mapred.TaskRunner: Task:attempt_local_0001_m_000000_0 is done. And is in the process of commiting
12/02/09 11:08:07 INFO mapred.LocalJobRunner: hdfs://localhost:9000/temp/in/t1.txt:0+52
12/02/09 11:08:07 INFO mapred.TaskRunner: Task 'attempt_local_0001_m_000000_0' done.
12/02/09 11:08:07 INFO mapred.MapTask: numReduceTasks: 1
12/02/09 11:08:07 INFO mapred.MapTask: io.sort.mb = 100
12/02/09 11:08:07 INFO mapred.MapTask: data buffer = 79691776/99614720
12/02/09 11:08:07 INFO mapred.MapTask: record buffer = 262144/327680
12/02/09 11:08:07 INFO test.WordCount: map 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: map 参数:key:0;value:cow is a cow.
12/02/09 11:08:07 INFO test.WordCount: word:cow
12/02/09 11:08:07 INFO test.WordCount: word:is
12/02/09 11:08:07 INFO test.WordCount: word:a
12/02/09 11:08:07 INFO test.WordCount: word:cow.
12/02/09 11:08:07 INFO test.WordCount: map 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: map 参数:key:15;value:word count job test.
12/02/09 11:08:07 INFO test.WordCount: word:word
12/02/09 11:08:07 INFO test.WordCount: word:count
12/02/09 11:08:07 INFO test.WordCount: word:job
12/02/09 11:08:07 INFO test.WordCount: word:test.
12/02/09 11:08:07 INFO mapred.MapTask: Starting flush of map output
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:a;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:count;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:cow;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:cow.;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:is;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:job;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:test.;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:word;values:[1]
12/02/09 11:08:07 INFO mapred.MapTask: Finished spill 0
12/02/09 11:08:07 INFO mapred.TaskRunner: Task:attempt_local_0001_m_000001_0 is done. And is in the process of commiting
12/02/09 11:08:07 INFO mapred.LocalJobRunner: hdfs://localhost:9000/temp/in/t2.txt:0+35
12/02/09 11:08:07 INFO mapred.TaskRunner: Task 'attempt_local_0001_m_000001_0' done.
12/02/09 11:08:07 INFO mapred.LocalJobRunner:
12/02/09 11:08:07 INFO mapred.Merger: Merging 2 sorted segments
12/02/09 11:08:07 INFO mapred.Merger: Down to the last merge-pass, with 2 segments left of total size: 180 bytes
12/02/09 11:08:07 INFO mapred.LocalJobRunner:
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:a;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:count;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:cow;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:cow.;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:do!;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:hello;values:[2]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:i;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:is;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:job;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:say;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:something.;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:test.;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:ufida!;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:word;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:world!;values:[1]
12/02/09 11:08:07 INFO test.WordCount: reduce 进程:Thread[Thread-14,5,main]
12/02/09 11:08:07 INFO test.WordCount: reduce 参数:key:yes;values:[1]
12/02/09 11:08:07 INFO mapred.TaskRunner: Task:attempt_local_0001_r_000000_0 is done. And is in the process of commiting
12/02/09 11:08:07 INFO mapred.LocalJobRunner:
12/02/09 11:08:07 INFO mapred.TaskRunner: Task attempt_local_0001_r_000000_0 is allowed to commit now
12/02/09 11:08:07 INFO mapred.FileOutputCommitter: Saved output of task 'attempt_local_0001_r_000000_0' to hdfs://localhost:9000/temp/out
12/02/09 11:08:07 INFO mapred.LocalJobRunner: reduce > reduce
12/02/09 11:08:07 INFO mapred.TaskRunner: Task 'attempt_local_0001_r_000000_0' done.
12/02/09 11:08:07 INFO mapred.JobClient:  map 100% reduce 100%
12/02/09 11:08:07 INFO mapred.JobClient: Job complete: job_local_0001
12/02/09 11:08:07 INFO mapred.JobClient: Counters: 15
12/02/09 11:08:07 INFO mapred.JobClient:   FileSystemCounters
12/02/09 11:08:07 INFO mapred.JobClient:     FILE_BYTES_READ=62828
12/02/09 11:08:07 INFO mapred.JobClient:     HDFS_BYTES_READ=62311
12/02/09 11:08:07 INFO mapred.JobClient:     FILE_BYTES_WRITTEN=63761
12/02/09 11:08:07 INFO mapred.JobClient:     HDFS_BYTES_WRITTEN=125860
12/02/09 11:08:07 INFO mapred.JobClient:   Map-Reduce Framework
12/02/09 11:08:07 INFO mapred.JobClient:     Reduce input groups=16
12/02/09 11:08:07 INFO mapred.JobClient:     Combine output records=16
12/02/09 11:08:07 INFO mapred.JobClient:     Map input records=5
12/02/09 11:08:07 INFO mapred.JobClient:     Reduce shuffle bytes=0
12/02/09 11:08:07 INFO mapred.JobClient:     Reduce output records=16
12/02/09 11:08:07 INFO mapred.JobClient:     Spilled Records=32
12/02/09 11:08:07 INFO mapred.JobClient:     Map output bytes=154
12/02/09 11:08:07 INFO mapred.JobClient:     Map input bytes=87
12/02/09 11:08:07 INFO mapred.JobClient:     Combine input records=17
12/02/09 11:08:07 INFO mapred.JobClient:     Map output records=17
12/02/09 11:08:07 INFO mapred.JobClient:     Reduce input records=16

  本以为hadoop会开很多线程来运行一个job,但是从日志“Thread[Thread-14,5,main]”可以看出其实一直都是一个线程在运行,可能是因为数据量太小,没有超过一个块的大小,所以只开了一个线程吧。具体以后再研究一下源码。仔细看日志,可以发现其大概运行过程如下(伪代码):

checkNumberPath();//检查输入文件个数(2个)
for(i=0;i<2;i++){
Array lines = readFile(i);//读取文件所有的行
for(line : lines){
map();//解析出word,添加到Collector
combine();
}
}
reduce();
  从日志最后几行,map过程、combine过程、reduce过程 之前之后多少个输入和输出也能可能出大概过程。

运维网声明 1、欢迎大家加入本站运维交流群:群②:261659950 群⑤:202807635 群⑦870801961 群⑧679858003
2、本站所有主题由该帖子作者发表,该帖子作者与运维网享有帖子相关版权
3、所有作品的著作权均归原作者享有,请您和我们一样尊重他人的著作权等合法权益。如果您对作品感到满意,请购买正版
4、禁止制作、复制、发布和传播具有反动、淫秽、色情、暴力、凶杀等内容的信息,一经发现立即删除。若您因此触犯法律,一切后果自负,我们对此不承担任何责任
5、所有资源均系网友上传或者通过网络收集,我们仅提供一个展示、介绍、观摩学习的平台,我们不对其内容的准确性、可靠性、正当性、安全性、合法性等负责,亦不承担任何法律责任
6、所有作品仅供您个人学习、研究或欣赏,不得用于商业或者其他用途,否则,一切后果均由您自己承担,我们对此不承担任何法律责任
7、如涉及侵犯版权等问题,请您及时通知我们,我们将立即采取措施予以解决
8、联系人Email:admin@iyunv.com 网址:www.yunweiku.com

所有资源均系网友上传或者通过网络收集,我们仅提供一个展示、介绍、观摩学习的平台,我们不对其承担任何法律责任,如涉及侵犯版权等问题,请您及时通知我们,我们将立即处理,联系人Email:kefu@iyunv.com,QQ:1061981298 本贴地址:https://www.yunweiku.com/thread-312524-1-1.html 上篇帖子: Hadoop之CommonConfigurationKeys解释,还望共同指正问号处 下篇帖子: Something stuff of Apress-Pro Hadoop(be going on...)
您需要登录后才可以回帖 登录 | 立即注册

本版积分规则

扫码加入运维网微信交流群X

扫码加入运维网微信交流群

扫描二维码加入运维网微信交流群,最新一手资源尽在官方微信交流群!快快加入我们吧...

扫描微信二维码查看详情

客服E-mail:kefu@iyunv.com 客服QQ:1061981298


QQ群⑦:运维网交流群⑦ QQ群⑧:运维网交流群⑧ k8s群:运维网kubernetes交流群


提醒:禁止发布任何违反国家法律、法规的言论与图片等内容;本站内容均来自个人观点与网络等信息,非本站认同之观点.


本站大部分资源是网友从网上搜集分享而来,其版权均归原作者及其网站所有,我们尊重他人的合法权益,如有内容侵犯您的合法权益,请及时与我们联系进行核实删除!



合作伙伴: 青云cloud

快速回复 返回顶部 返回列表