并发场景org.apache.http.conn.ConnectionPoolTimeoutException异常分析

起因

线上运行的公众号模板消息批量推送时会有10%左右的失败,这在测试环境下并未出现,日志定位为如下错误:

org.apache.http.conn.ConnectionPoolTimeoutException: Timeout waiting for connection from pool
    at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.leaseConnection(PoolingHttpClientConnectionManager.java:316)
    at org.apache.http.impl.conn.PoolingHttpClientConnectionManager$1.get(PoolingHttpClientConnectionManager.java:282)
    at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:190)

先说结果

使用的公众号开发SDK使用HttpClient作为HTTP请求工具,并默认设置connectionRequestTimeout=3000。此问题与请求服务端无关,抛出这个异常时请求还未从客户端发出,它实际是由于大量HTTP请求需处理时连接池没有可用连接,等待超过设置时间抛出异常。虽然我们发送模板消息时使用了线程池,但线程池最大线程数(30)多于HttpClient默认maxConnPerHost连接池数量(10),所以网络请求相对较慢依然会造成请求堆积。
connectionRequestTimeout=3000参数的含义:当一个线程需要发送HTTP请求时,从连接池取一个连接,如果等待3秒还未取到可用连接,那么直接抛出异常不再发送此请求。
maxConnPerHost参数含义:默认是10,每个服务域名最多给多少个连接,一般少于总连接数
所以解决这个问题有以下几种方式可以解决

  • 更改connectionRequestTimeout参数,调到更长时间可减少此异常发生几率,或者直接设置为-1让此参数失效
  • 多线程HTTP请求时控制线程数不要超过maxConnPerHost
  • 如机器性能较号可调高maxConnPerHost参数,增加允许并发量

分析

推送模板消息实际是通过构造参数发送POST请求腾讯微信公众平台,测试环境没有进行大量并发测试(推送模板消息需要真实的粉丝用户openid,测试号没有很多粉丝)。那么是使用配置不当?网络不稳定导致请求发送不成功?还是HttpClient 并发有bug?HttpClient 是apache出品的成熟工具,发送模板消息使用的SDK已做了请求失败重试,还是先检查是不是自己使用配置的问题。

我们知道HttpClient是有配置连接池的,这个错误一看就能大概猜到是从连接池获取连接超时,可是为什么会出现这个错误?超出连接池连接数量的请求不是应该在排队等待?

经过模拟脚本模仿生产环境配置参数,并多次调整配置参数测试,定位问题为connectionRequestTimeout参数配置不当。

模拟测试

模拟环境如下

  • JDK1.8
  • HttpClient 4.5.11

模拟代码如下

import org.apache.http.Consts;
import org.apache.http.client.config.RequestConfig;
import org.apache.http.client.methods.CloseableHttpResponse;
import org.apache.http.client.methods.HttpPost;
import org.apache.http.entity.StringEntity;
import org.apache.http.impl.client.BasicResponseHandler;
import org.apache.http.impl.client.CloseableHttpClient;
import org.apache.http.impl.client.HttpClients;

import java.io.IOException;
import java.util.concurrent.CountDownLatch;
import java.util.concurrent.ExecutorService;
import java.util.concurrent.Executors;
import java.util.concurrent.atomic.AtomicInteger;

public class Test {
    static CloseableHttpClient httpClient = HttpClients.createDefault();
    static ExecutorService excutor = Executors.newFixedThreadPool(30);
    static RequestConfig requestConfig = RequestConfig.custom()
            .setConnectionRequestTimeout(100).build();//设置获取连接超时时间,为重现问题这里故意设置比较小
    static final String TEST_URL = "http://pv.sohu.com/cityjson";//测试链接,这里使用搜狐的开放IP查询接口

    public static void main(String[] args) throws InterruptedException {
        final int testCount = 500;//任务重复次数
        AtomicInteger successCount = new AtomicInteger(0);//请求成功数量
        CountDownLatch latch  = new CountDownLatch(testCount);//用于判断线程池中任务是否全部执行完毕
        Long time1 = System.currentTimeMillis();
        for (int i = 0; i < testCount; i++) {
            excutor.submit(()->{
                String res = httpPost(TEST_URL,null);
                System.out.println(res);
                if(null!=res && !res.isEmpty())successCount.addAndGet(1);
                latch.countDown();
            });
        }
        latch.await();//等待线程池中的线程全部执行完
        Long time2 = System.currentTimeMillis();
        System.out.println("耗时:"+(time2-time1)+"毫秒,成功:"+successCount.get());
        excutor.shutdown();
    }
    public static String httpPost(String uri,String data){
        HttpPost  post = new HttpPost(uri);
        post.setConfig(requestConfig);
        if(data!=null){
            StringEntity entity = new StringEntity(data, Consts.UTF_8);
            post.setEntity(entity);
        }
        CloseableHttpResponse response=null;
        try {
            response = httpClient.execute(post);
            int statusCode = response.getStatusLine().getStatusCode();
            return new BasicResponseHandler().handleResponse(response);
        } catch (IOException e) {
            e.printStackTrace();
        }finally {
            post.releaseConnection();
        }
        return null;
    }
}

测试结果,500个请求成功27个😂,大量的ConnectionPoolTimeoutException

耗时:862毫秒,成功:27

调整参数测试

static RequestConfig requestConfig = RequestConfig.custom()
            .setConnectionRequestTimeout(1000).build();//设置请求连接超时时间
  • 请求连接超时时间:1000 (1秒)

    耗时:9459毫秒,成功:420

  • 请求连接超时时间:3000 (3秒)

    耗时:11093毫秒,成功:500

说明这个模拟测试脚本里面,请求连接超时时间设置到3S可以达到100%成功,但是生产环境对发送成功率要求很高,设置3S超时合适吗?所以再看看HttpClient源码来找找答案:

//获取连接关键代码,有精简:org.apache.http.impl.conn.PoolingHttpClientConnectionManager#leaseConnection
protected HttpClientConnection leaseConnection(Future<CPoolEntry> future, long timeout, TimeUnit timeUnit) throws InterruptedException, ExecutionException, ConnectionPoolTimeoutException {
        try {
            CPoolEntry entry = (CPoolEntry)future.get(timeout, timeUnit);
            if (entry != null && !future.isCancelled()) {
                Asserts.check(entry.getConnection() != null, "Pool entry with no connection");
                //...
                return CPoolProxy.newProxy(entry);
            } else {
                throw new ExecutionException(new CancellationException("Operation cancelled"));
            }
        } catch (TimeoutException var7) {
            throw new ConnectionPoolTimeoutException("Timeout waiting for connection from pool");
        }
    }

其中连接从Future<CPoolEntry>中获取,其默认connectionRequestTimeout=-1,也就是永不过期,生产环境要求请求一定要成功,所以设置一直等待获取连接即可!

配置参考

connectionRequestTimout:指从连接池获取连接的timeout
connetionTimeout:指客户端和服务器建立连接的timeout,就是http请求的三个阶段,一:建立连接;二:数据传送;三,断开连接。超时后会ConnectionTimeOutException
socketTimeout:指客户端从服务器读取数据的timeout,超出后会抛出SocketTimeOutException
最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 219,589评论 6 508
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 93,615评论 3 396
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 165,933评论 0 356
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 58,976评论 1 295
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 67,999评论 6 393
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 51,775评论 1 307
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 40,474评论 3 420
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 39,359评论 0 276
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 45,854评论 1 317
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 38,007评论 3 338
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 40,146评论 1 351
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 35,826评论 5 346
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 41,484评论 3 331
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 32,029评论 0 22
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 33,153评论 1 272
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 48,420评论 3 373
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 45,107评论 2 356

推荐阅读更多精彩内容