Kafka又出問題了！

作者： 冰河 2021-03-02 06:02:03

開發(fā)

架構(gòu)

Kafka 作者個人研發(fā)的在高并發(fā)場景下，提供的簡單、穩(wěn)定、可擴展的延遲消息隊列框架，具有精準的定時任務和延遲隊列處理功能。

 作者個人研發(fā)的在高并發(fā)場景下，提供的簡單、穩(wěn)定、可擴展的延遲消息隊列框架，具有精準的定時任務和延遲隊列處理功能。自開源半年多以來，已成功為十幾家中小型企業(yè)提供了精準定時調(diào)度方案，經(jīng)受住了生產(chǎn)環(huán)境的考驗。為使更多童鞋受益，現(xiàn)給出開源框架地址：https://github.com/sunshinelyz/mykit-delay

寫在前面

估計運維年前沒有祭拜服務器，Nginx的問題修復了，Kafka又不行了。今天，本來想再睡會，結(jié)果，電話又響了。還是運營，“喂，冰河，到公司了嗎?趕緊看看服務器吧，又出問題了“?！霸诼飞狭?，運維那哥們兒還沒上班嗎”?“還在休假。。。”， 我：“。。。”。哎，這哥們兒是跑路了嗎?先不管他，問題還是要解決。

問題重現(xiàn)

到公司后，放下我專用的雙肩包，拿出我的利器——筆記本電腦，打開后迅速登錄監(jiān)控系統(tǒng)，發(fā)現(xiàn)主要業(yè)務系統(tǒng)沒啥問題。一個非核心服務發(fā)出了告警，并且監(jiān)控系統(tǒng)中顯示這個服務頻繁的拋出如下異常。

  
 
 
 

   
  
  
  
2021-02-28 22:03:05 131 pool-7-thread-3 ERROR [] - 
   
  
  
  
commit failed 
   
  
  
  
org.apache.kafka.clients.consumer.CommitFailedException: Commit cannot be completed since the group has already rebalanced and assigned the partitions to another member. This means that the time between subsequent calls to poll() was longer than the configured max.poll.interval.ms, which typically implies that the poll loop is spending too much time message processing. You can address this either by increasing the session timeout or by reducing the maximum size of batches returned in poll() with max.poll.records.
   
  
  
  
        at org.apache.kafka.clients.consumer.internals.ConsumerCoordinator.sendOffsetCommitRequest(ConsumerCoordinator.java:713) ~[MsgAgent-jar-with-dependencies.jar:na]
   
  
  
  
        at org.apache.kafka.clients.consumer.internals.ConsumerCoordinator.commitOffsetsSync(ConsumerCoordinator.java:596) ~[MsgAgent-jar-with-dependencies.jar:na]
   
  
  
  
        at org.apache.kafka.clients.consumer.KafkaConsumer.commitSync(KafkaConsumer.java:1218) ~[MsgAgent-jar-with-dependencies.jar:na]
   
  
  
  
        at com.today.eventbus.common.MsgConsumer.run(MsgConsumer.java:121) ~[MsgAgent-jar-with-dependencies.jar:na]
   
  
  
  
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_161]
   
  
  
  
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_161]
   
  
  
  
        at java.lang.Thread.run(Thread.java:748) [na:1.8.0_161]
  
 
 
 

從上面輸出的異常信息，大概可以判斷出系統(tǒng)出現(xiàn)的問題：Kafka消費者在處理完一批poll消息后，在同步提交偏移量給broker時報錯了。大概就是因為當前消費者線程的分區(qū)被broker給回收了，因為Kafka認為這個消費者掛掉了，我們可以從下面的輸出信息中可以看出這一點。

  
 
 
 

   
  
  
  
Commit cannot be completed since the group has already rebalanced and assigned the partitions to another member. This means that the time between subsequent calls to poll() was longer than the configured max.poll.interval.ms, which typically implies that the poll loop is spending too much time message processing. You can address this either by increasing the session timeout or by reducing the maximum size of batches returned in poll() with max.poll.records.
  
 
 
 

Kafka內(nèi)部觸發(fā)了Rebalance機制，明確了問題，接下來，我們就開始分析問題了。

分析問題

既然Kafka觸發(fā)了Rebalance機制，那我就來說說Kafka觸發(fā)Rebalance的時機。

什么是Rebalance

舉個具體點的例子，比如某個分組下有10個Consumer實例，這個分組訂閱了一個50個分區(qū)的主題。正常情況下，Kafka會為每個消費者分配5個分區(qū)。這個分配的過程就是Rebalance。

觸發(fā)Rebalance的時機

當Kafka中滿足如下條件時，會觸發(fā)Rebalance：

• 組內(nèi)成員的個數(shù)發(fā)生了變化，比如有新的消費者加入消費組，或者離開消費組。組成員離開消費組包含組成員崩潰或者主動離開消費組。
• 訂閱的主題個數(shù)發(fā)生了變化。
• 訂閱的主題分區(qū)數(shù)發(fā)生了變化。

后面兩種情況我們可以人為的避免，在實際工作過程中，對于Kafka發(fā)生Rebalance最常見的原因是消費組成員的變化。

消費者成員正常的添加和停掉導致Rebalance，這種情況無法避免，但是時在某些情況下，Consumer 實例會被 Coordinator 錯誤地認為 “已停止” 從而被“踢出”Group，導致Rebalance。

當 Consumer Group 完成 Rebalance 之后，每個 Consumer 實例都會定期地向 Coordinator 發(fā)送心跳請求，表明它還存活著。如果某個 Consumer 實例不能及時地發(fā)送這些心跳請求，Coordinator 就會認為該 Consumer 已經(jīng) “死” 了，從而將其從 Group 中移除，然后開啟新一輪 Rebalance。這個時間可以通過Consumer 端的參數(shù) session.timeout.ms 進行配置。默認值是 10 秒。

除了這個參數(shù)，Consumer 還提供了一個控制發(fā)送心跳請求頻率的參數(shù)，就是 heartbeat.interval.ms。這個值設置得越小，Consumer 實例發(fā)送心跳請求的頻率就越高。頻繁地發(fā)送心跳請求會額外消耗帶寬資源，但好處是能夠更加快速地知曉當前是否開啟 Rebalance，因為，目前 Coordinator 通知各個 Consumer 實例開啟 Rebalance 的方法，就是將 REBALANCE_NEEDED 標志封裝進心跳請求的響應體中。

除了以上兩個參數(shù)，Consumer 端還有一個參數(shù)，用于控制 Consumer 實際消費能力對 Rebalance 的影響，即 max.poll.interval.ms 參數(shù)。它限定了 Consumer 端應用程序兩次調(diào)用 poll 方法的最大時間間隔。它的默認值是 5 分鐘，表示 Consumer 程序如果在 5 分鐘之內(nèi)無法消費完 poll 方法返回的消息，那么 Consumer 會主動發(fā)起 “離開組” 的請求，Coordinator 也會開啟新一輪 Rebalance。

通過上面的分析，我們可以看一下那些rebalance是可以避免的：

第一類非必要 Rebalance 是因為未能及時發(fā)送心跳，導致 Consumer 被 “踢出”Group 而引發(fā)的。這種情況下我們可以設置 session.timeout.ms 和 heartbeat.interval.ms 的值，來盡量避免rebalance的出現(xiàn)。(以下的配置是在網(wǎng)上找到的最佳實踐，暫時還沒測試過)

• 設置 session.timeout.ms = 6s。
• 設置 heartbeat.interval.ms = 2s。
• 要保證 Consumer 實例在被判定為 “dead” 之前，能夠發(fā)送至少 3 輪的心跳請求，即 session.timeout.ms >= 3 * heartbeat.interval.ms。

將 session.timeout.ms 設置成 6s 主要是為了讓 Coordinator 能夠更快地定位已經(jīng)掛掉的 Consumer，早日把它們踢出 Group。

第二類非必要 Rebalance 是 Consumer 消費時間過長導致的。此時，max.poll.interval.ms 參數(shù)值的設置顯得尤為關鍵。如果要避免非預期的 Rebalance，最好將該參數(shù)值設置得大一點，比下游最大處理時間稍長一點。

總之，要為業(yè)務處理邏輯留下充足的時間。這樣，Consumer 就不會因為處理這些消息的時間太長而引發(fā) Rebalance 。

拉取偏移量與提交偏移量

kafka的偏移量(offset)是由消費者進行管理的，偏移量有兩種，拉取偏移量(position)與提交偏移量(committed)。拉取偏移量代表當前消費者分區(qū)消費進度。每次消息消費后，需要提交偏移量。在提交偏移量時，kafka會使用拉取偏移量的值作為分區(qū)的提交偏移量發(fā)送給協(xié)調(diào)者。

如果沒有提交偏移量，下一次消費者重新與broker連接后，會從當前消費者group已提交到broker的偏移量處開始消費。

所以，問題就在這里，當我們處理消息時間太長時,已經(jīng)被broker剔除，提交偏移量又會報錯。所以拉取偏移量沒有提交到broker，分區(qū)又rebalance。下一次重新分配分區(qū)時，消費者會從最新的已提交偏移量處開始消費。這里就出現(xiàn)了重復消費的問題。

異常日志提示的方案

其實，說了這么多，Kafka消費者輸出的異常日志中也給出了相應的解決方案。

接下來，我們說說Kafka中的拉取偏移量和提交偏移量。

其實，從輸出的日志信息中，也大概給出了解決問題的方式，簡單點來說，就是可以通過增加 max.poll.interval.ms 時長和 session.timeout.ms時長，減少 max.poll.records的配置值，并且消費端在處理完消息時要及時提交偏移量。

問題解決

通過之前的分析，我們應該知道如何解決這個問題了。這里需要說一下的是，我在集成Kafka的時候，使用的是SpringBoot和Kafka消費監(jiān)聽器，消費端的主要代碼結(jié)構(gòu)如下所示。

  
 
 
 

   
  
  
  
@KafkaListener(topicPartitions = {@TopicPartition(topic = KafkaConstants.TOPIC_LOGS, partitions = { "0" }) }, groupId = "kafka-consumer", containerFactory = "kafkaListenerContainerFactory")
   
  
  
  
public void consumerReceive (ConsumerRecord record, Acknowledgment ack){
   
  
  
  
    logger.info("topic is {}, offset is {}, value is {} n", record.topic(), record.offset(), record.value());
   
  
  
  
    try {
   
  
  
  
        Object value = record.value();
   
  
  
  
        logger.info(value.toString());
   
  
  
  
        ack.acknowledge();
   
  
  
  
    } catch (Exception e) {
   
  
  
  
        logger.error("日志消費端異常: {}", e);
   
  
  
  
    }
   
  
  
  
}
  
 
 
 

上述代碼邏輯比較簡單，就是獲取到Kafka中的消息后直接打印輸出到日志文件中。

嘗試解決

這里，我先根據(jù)異常日志的提示信息進行配置，所以，我在SpringBoot的application.yml文件中新增了如下配置信息。

  
 
 
 

   
  
  
  
spring:
   
  
  
  
  kafka:
   
  
  
  
    consumer:
   
  
  
  
    properties:
   
  
  
  
     max.poll.interval.ms: 3600000
   
  
  
  
     max.poll.records: 50
   
  
  
  
     session.timeout.ms: 60000
   
  
  
  
     heartbeat.interval.ms: 3000
  
 
 
 

配置完成后，再次測試消費者邏輯，發(fā)現(xiàn)還是拋出Rebalance異常。

最終解決

我們從另一個角度來看下Kafka消費者所產(chǎn)生的問題：一個Consumer在生產(chǎn)消息，另一個Consumer在消費它的消息，它們不能在同一個groupId 下面，更改其中一個的groupId 即可。

這里，我們的業(yè)務項目是分模塊和子系統(tǒng)進行開發(fā)的，例如模塊A在生產(chǎn)消息，模塊B消費模塊A生產(chǎn)的消息。此時，修改配置參數(shù)，例如 session.timeout.ms: 60000，根本不起作用，還是拋出Rebalance異常。

此時，我嘗試修改下消費者分組的groupId，將下面的代碼

  
 
 
 

   
  
  
  
@KafkaListener(topicPartitions = {@TopicPartition(topic = KafkaConstants.TOPIC_LOGS, partitions = { "0" }) }, groupId = "kafka-consumer", containerFactory = "kafkaListenerContainerFactory")
   
  
  
  
public void consumerReceive (ConsumerRecord record, Acknowledgment ack){
  
 
 
 

修改為如下所示的代碼。

  
 
 
 

   
  
  
  
@KafkaListener(topicPartitions = {@TopicPartition(topic = KafkaConstants.TOPIC_LOGS, partitions = { "0" }) }, groupId = "kafka-consumer-logs", containerFactory = "kafkaListenerContainerFactory")
   
  
  
  
public void consumerReceive (ConsumerRecord record, Acknowledgment ack){
  
 
 
 

再次測試，問題解決~~

 本文轉(zhuǎn)載自微信公眾號「冰河技術」，可以通過以下二維碼關注。轉(zhuǎn)載本文請聯(lián)系冰河技術公眾號。

網(wǎng)站題目：Kafka又出問題了！
轉(zhuǎn)載來源：http://fisionsoft.com.cn/article/dhcccso.html