一篇很好的解决系统问题过程描述文章

[size=large][color=darkred]在网上看到的一篇解决hbase性能问题的文章，虽然文章不长，但是我相信作者在此经历的过程和从中学到的知识要比这个深刻的太多了。[/color][/size]
[b]原文地址：[url]http://tech.meituan.com/opentsdb_hbase_compaction_problem.html[/url][/b]

[b][size=medium]业务背景[/size][/b]

OpenTSDB 是一款非常适合存储海量时间序列数据的开源软件，使用 HBase 作为存储让它变的非常容易扩展。我们在建设美团性能监控平台的过程中，每天需要处理数以亿计的数据，经过几番探索和调研，最终选取了 OpenTSDB 作为数据存储层的重要组件。OpenTSDB 的安装和配置过程都比较简单，但是在实际的业务应用中，还是会出现这样那样的问题，本文详细介绍我们在OpenTSDB 实际使用过程中遇到的 HBase 整点压力过大的问题，期望对大家有些参考意义。

[b][size=medium]问题的出现[/size][/b]

性能监控平台使用 OpenTSDB 负责存储之后不久（创建的表名称是 tsdb-perf），数据平台组的同事发现，tsdb-perf 这个表在最近这段时间每天上午 10 点左右有大量的读操作，造成 HBase 集群压力过大，但是想去分析问题的时候发现读操作又降为 0 了，为了避免类似情况未来突然发生，需要我来排查下原因。

于是我就想：性能监控平台目前只是个内部系统，用户使用量不大，并且只有在用户需要查看数据时去查询，数据读取量不应该造成 HBase 的压力过大。

[b][size=medium]重现问题[/size][/b]
如果要解决这个问题，稳定重现是个必要条件，根据数据平台组同事的反馈，我们做了更详细的监控，每隔两分钟采集性能监控平台所用的 HBase 集群的读操作数量，发现是下面的变化趋势：

13:00:05    0
13:02:01    66372
13:04:01    96746
13:06:02    101784
13:08:01    99254
13:10:02    2814
13:12:01    93668
13:14:02    93224
13:16:02    90118
13:18:02    11376
13:20:01    85134
13:22:01    81880
13:24:01    80916
13:26:01    77694
13:28:02    76312
13:30:01    73310
13:32:02    0
13:34:01    0
13:36:01    0
13:38:02    0
13:40:01    0
13:42:02    0
13:44:01    0
13:46:02    0
13:48:01    0
13:50:02    0
13:52:01    0
13:54:02    0
13:56:01    0
13:58:02    0
14:00:01    0
14:02:01    36487
14:04:01    43946
14:06:01    53002
14:08:02    51598
14:10:01    54914
14:12:02    95784
14:14:04    53866
14:16:02    54868
14:18:01    54122
14:20:04    0
14:22:01    0
14:24:02    0
14:26:01    0
14:28:01    0
14:30:01    0
14:32:02    0
14:34:01    0

从图上不难看出，每到整点开始 tsdb-perf 这个表的读操作飚的很高，大约持续半个小时，之后恢复到 0 。到下个整点又出现类似的问题，并没有像数据平台组同事观察到的突然回复正常了，可能他们连续两次观察的时间点刚好错开了。

于是，真正的问题就变成了：OpenTSDB 到 HBase 的读操作每到整点开始飚的很高，持续大约半小时后回复正常，这种类脉冲式的流量冲击会给 HBase 集群的稳定性带来负面影响。

[b][size=medium]定位问题所在[/size][/b]
事出反常必有妖，OpenTSDB 到 HBase 的大量读操作肯定伴随很大的网络流量，因为两者用 HTTP 通信，我们得仔细梳理下可能造成这种情况的几种原因。性能监控平台的架构图如下：

[img]http://dl2.iteye.com/upload/attachment/0111/9076/8757042e-c27c-3430-bd1a-f91e89f23ca9.jpg[/img]


从架构图可以看出，只有数据聚合服务和报表系统会和 OpenTSDB 交互，聚合服务向里面写数据，报表系统从里面读数据。然后 OpenTSDB 负责把数据发送到 HBase 中。从数据流动的方向来讲，有可能是报表系统导致了大量的读操作，也有可能是聚合服务里面存在不合理的读请求，也有可能是 OpenTSDB 本身存在缺陷。

首先排除的是报表系统导致的大量读操作，因为只会在用户查看某些报表时才会从 OpenTSDB 读取数据，目前报表系统每天的访问量也才几百，不足以造成如此大的影响。

其次，如何确认是否是聚合服务导致了大量的读请求呢？可以从网络流量的视角来分析，如果聚合服务到 OpenTSDB 的流量过大，完全有可能导致 OpenTSDB 到 HBase 的过大流量，但是由于目前聚合服务和 TSDB 写实例是部署在相同的机器上，无法方便的统计到网络流量的大小，于是我们把聚合服务和 TSDB 写实例分开部署，得到下面的流量统计图：


聚合服务只接收来自解析服务的数据包计算完毕之后发送给 TSDB，其网络流量如下图：

[img]http://dl2.iteye.com/upload/attachment/0111/9078/4a65f1b1-983f-35a9-8a83-2fecfbfb5b63.jpg[/img]

TSDB 服务只接收来自聚合服务的数据，然后发送到 HBase，却出现了脉冲式的冲高回落，网络流量如下图：

[img]http://dl2.iteye.com/upload/attachment/0111/9080/579ecadb-c216-3ab6-8e5d-4913a7ed3519.jpg[/img]


这样，就可以排除聚合服务造成的问题，出问题的地方就在 OpenTSDB 和 HBase 集群之间，其他业务线并没有造成 HBase 的压力过大，看来问题应该出在 OpenTSDB 里面，如果这个问题是 OpenTSDB 内部存在的，那么其他使用了 OpenTSDB 的系统肯定也存在类似的问题，下面是另外一个组部署的 OpenTSDB 的机器的网络流量图（注意，这台机器上只部署了 OpenTSDB 服务）：

[img]http://dl2.iteye.com/upload/attachment/0111/9082/6fa2b606-f0be-3d4e-bf43-7b22b251a059.jpg[/img]

这让我更加确信问题是在 OpenTSDB 内部，也就是它的工作机制导致了这种问题。
查找问题原因
于是我先后查阅了 OpenTSDB 的官方文档和 Google Group 讨论组里的大部分帖子，还下载来了 OpenTSDB 的源代码，探个究竟，另外在从读操作从 0 到暴涨的过程中仔细盯着 OpenTSDB 的 stat 页面特别关注下面红色方框中的几个指标：

[img]http://dl2.iteye.com/upload/attachment/0111/9084/e08c4058-7fd7-3e73-b713-b9fa2c305e2d.jpg[/img]

让我感觉比较诡异的是，与大量读操作同时发生的还有大量的删除操作，官方文档上的这段话很好的解释了我的疑惑：

If compactions have been enabled for a TSD, a row may be compacted after it's base hour has passed or a query has run over the row. Compacted columns simply squash all of the data points together to reduce the amount of overhead consumed by disparate data points. Data is initially written to individual columns for speed, then compacted later for storage efficiency. Once a row is compacted, the individual data points are deleted. Data may be written back to the row and compacted again later.

这段话很好的解释了 OpenTSDB 的 Compaction 机制的工作原理，OpenTSDB 内部的工作原理比这个更复杂，下面我说下我通俗的理解：

为了节省存储空间和提高数据读取速度，OpenTSDB 内部有个数据压缩（即 Compaction）的机制，将设定的某个时间段内某个指标的所有数据压缩成单行，重新写到 HBase；
OpenTSDB 运行时默认把收到的数据（原始数据点）每秒1次的速度批量写到 HBase 上，然后会周期性的触发上面提到的数据压缩机制，把原始数据点拿出来，压缩后重新写回HBase，然后把原始数据点删除，这就虽然我们只往 OpenTSDB 写数据但大量的读和删操作还是会发生的原因；
OpenTSDB 默认的配置是以 3600 秒为区间压缩，实际运行时就是整点触发，这样整点发生的大量读、删操作就可以解释了；
至此，线上 OpenTSDB 实例整点大量读操作造成 HBase 集群压力过大的问题原因基本明了。

[b][size=medium]如何解决问题[/size][/b]
找到问题的原因之后，我们想到了以下 2 种解决方案：

禁用 OpenTSDB 的 Compaction 机制，这样 OpenTSDB 就变成了 1 个纯粹的写实例，数据读取速度会有牺牲，因为每次读取需要扫描更多的数据，这个对于业务数据量很大的系统来说可能并不合适；
想办法让 OpenTSDB 的数据压缩过程缓慢进行，这样到 HBase 的流量压力就会平缓很多，但是这样做还是有风险，因为如果从业务系统到 OpenTSDB 的流量暴涨仍然有可能会 HBase 压力过大，不过这就是另外1个问题了，HBase 需要扩容；
实际操作过程中，我们使用了第 2 种方案，修改 OpenTSDB 的源代码中 src/core/CompactionQueue.java 中的 FLUSH_SPEED 常量为 1，重新编译即可。这样改动的实际影响是：默认压缩速度是 2 倍速，即最多半个小时内完成前 1 个小时数据的压缩，重新写回到 HBase，可以把这个调成 1 倍速，给它 1 个小时的时间来完成前 1 个小时数据的 Compaction，这样到 HBase 的流量会平缓很多。

[/size][/b]经验和教训[/size][/b]
几经辗转，终于找到问题原因所在（离解决问题还有距离），下面是我的几点感受：

解决问题之前，要能够稳定重现，找到真正的问题所在，不能停留在表面，如果不进行几个小时的 HBase 读操作监控，不会发现整点暴涨持续半小时然后回落的问题；
系统的运行环境很复杂，必要的时候要想办法把问题隔离到影响因素更少的环境中，更容易发现问题，比如性能监控平台各组件的混合部署给机器间的流量分析造成了困难；
使用开源软件，最好能深入了解下运行机制，用起来才得心应手，不然出了问题就很麻烦，这次的排查过程让我更加详细的了解了 OpenTSDB 的运行机制；
至此，本文完~